builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1487217846.23 results: success (0) revision: a9ec72f82299250e6023988e238931bbca0ef7fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.234487) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.234953) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.235239) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.247173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.247554) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-15 20:04:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-02-15 20:04:06 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096971 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.379014) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.379354) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019288 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:06.483268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 20:04:06.483612) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a9ec72f82299250e6023988e238931bbca0ef7fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev a9ec72f82299250e6023988e238931bbca0ef7fa --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-15 20:04:06,663 truncating revision to first 12 chars 2017-02-15 20:04:06,663 Setting DEBUG logging. 2017-02-15 20:04:06,663 attempt 1/10 2017-02-15 20:04:06,663 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a9ec72f82299?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-15 20:04:07,759 unpacking tar archive at: mozilla-central-a9ec72f82299/testing/mozharness/ program finished with exit code 0 elapsedTime=1.400145 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 20:04:07.897016) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:07.897334) ========= script_repo_revision: a9ec72f82299250e6023988e238931bbca0ef7fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:07.897708) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:07.897965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 20:04:07.908365) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-02-15 20:04:07.908859) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:04:08 INFO - MultiFileLogger online at 20170215 20:04:08 in /builds/slave/test 20:04:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:04:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:04:08 INFO - {'append_to_log': False, 20:04:08 INFO - 'base_work_dir': '/builds/slave/test', 20:04:08 INFO - 'blob_upload_branch': 'Firefox', 20:04:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:04:08 INFO - 'branch': 'Firefox', 20:04:08 INFO - 'buildbot_json_path': 'buildprops.json', 20:04:08 INFO - 'config_files': ('talos/linux_config.py',), 20:04:08 INFO - 'default_actions': ('clobber', 20:04:08 INFO - 'read-buildbot-config', 20:04:08 INFO - 'download-and-extract', 20:04:08 INFO - 'populate-webroot', 20:04:08 INFO - 'create-virtualenv', 20:04:08 INFO - 'install', 20:04:08 INFO - 'run-tests'), 20:04:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:04:08 INFO - 'download_minidump_stackwalk': True, 20:04:08 INFO - 'download_symbols': 'ondemand', 20:04:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:04:08 INFO - 'tooltool.py': '/tools/tooltool.py', 20:04:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:04:08 INFO - '/tools/misc-python/virtualenv.py')}, 20:04:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:04:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:04:08 INFO - 'installer_path': 'installer.exe', 20:04:08 INFO - 'log_level': 'info', 20:04:08 INFO - 'log_name': 'talos', 20:04:08 INFO - 'log_to_console': True, 20:04:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:04:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:04:08 INFO - 'opt_config_files': (), 20:04:08 INFO - 'pip_index': False, 20:04:08 INFO - 'sps_profile': False, 20:04:08 INFO - 'sps_profile_interval': 0, 20:04:08 INFO - 'suite': 'dromaeojs', 20:04:08 INFO - 'system_bits': '32', 20:04:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:04:08 INFO - 'title': 'talos-linux64-ix-058', 20:04:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:04:08 INFO - 'use_talos_json': True, 20:04:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:04:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:04:08 INFO - 'work_dir': 'build'} 20:04:08 INFO - [mozharness: 2017-02-16 04:04:08.182012Z] Running clobber step. 20:04:08 INFO - Running pre-action listener: _resource_record_pre_action 20:04:08 INFO - Running main action method: clobber 20:04:08 INFO - rmtree: /builds/slave/test/build 20:04:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:04:09 INFO - Running post-action listener: _resource_record_post_action 20:04:09 INFO - [mozharness: 2017-02-16 04:04:09.106414Z] Finished clobber step (success) 20:04:09 INFO - [mozharness: 2017-02-16 04:04:09.106514Z] Running read-buildbot-config step. 20:04:09 INFO - Running pre-action listener: _resource_record_pre_action 20:04:09 INFO - Running main action method: read_buildbot_config 20:04:09 INFO - Using buildbot properties: 20:04:09 INFO - { 20:04:09 INFO - "project": "", 20:04:09 INFO - "product": "firefox", 20:04:09 INFO - "who": "philringnalda@gmail.com", 20:04:09 INFO - "installer_path": "public/build/target.tar.bz2", 20:04:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 20:04:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:04:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 20:04:09 INFO - "stage_platform": "linux64-pgo", 20:04:09 INFO - "basedir": "/builds/slave/test", 20:04:09 INFO - "buildnumber": 34, 20:04:09 INFO - "platform": "ubuntu64_hw", 20:04:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:04:09 INFO - "slavebuilddir": "test", 20:04:09 INFO - "taskId": "IL7sSOYLR4G7XfX8fqLGAA", 20:04:09 INFO - "branch": "mozilla-central", 20:04:09 INFO - "script_repo_revision": "production", 20:04:09 INFO - "revision": "a9ec72f82299250e6023988e238931bbca0ef7fa", 20:04:09 INFO - "slavename": "talos-linux64-ix-058", 20:04:09 INFO - "repo_path": "mozilla-central" 20:04:09 INFO - } 20:04:09 INFO - Finding installer, test and symbols from parent task. 20:04:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IL7sSOYLR4G7XfX8fqLGAA'}, attempt #1 20:04:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IL7sSOYLR4G7XfX8fqLGAA'}, attempt #1 20:04:10 INFO - Task dependencies: DQlWbmmJQkmazp498XDVtg 20:04:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg'}, attempt #1 20:04:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2 20:04:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/test_packages.json 20:04:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.crashreporter-symbols.zip 20:04:10 INFO - Running post-action listener: _resource_record_post_action 20:04:10 INFO - [mozharness: 2017-02-16 04:04:10.483817Z] Finished read-buildbot-config step (success) 20:04:10 INFO - [mozharness: 2017-02-16 04:04:10.484036Z] Running download-and-extract step. 20:04:10 INFO - Running pre-action listener: _resource_record_pre_action 20:04:10 INFO - Running main action method: download_and_extract 20:04:10 INFO - mkdir: /builds/slave/test/build/tests 20:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:10 INFO - trying https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.test_packages.json 20:04:10 INFO - Downloading https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:04:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:04:11 INFO - Downloaded 896 bytes. 20:04:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:04:11 INFO - Using the following test package requirements: 20:04:11 INFO - {u'common': [u'target.common.tests.zip'], 20:04:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:04:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:04:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:04:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:04:11 INFO - u'mozbase': [u'target.common.tests.zip'], 20:04:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:04:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:04:11 INFO - u'web-platform': [u'target.common.tests.zip', 20:04:11 INFO - u'target.web-platform.tests.zip'], 20:04:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:04:11 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:04:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.common.tests.zip 20:04:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:04:11 INFO - Fetch https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.common.tests.zip into memory 20:04:12 INFO - Expected file size: 59050792 20:04:12 INFO - Obtained file size: 59050792 20:04:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:04:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.common.tests.zip 20:04:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:04:21 INFO - Fetch https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.common.tests.zip into memory 20:04:23 INFO - Expected file size: 59050792 20:04:23 INFO - Obtained file size: 59050792 20:04:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.talos.tests.zip 20:04:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:04:33 INFO - Fetch https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.talos.tests.zip into memory 20:04:34 INFO - Expected file size: 14403647 20:04:34 INFO - Obtained file size: 14403647 20:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:34 INFO - trying https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2 20:04:34 INFO - Downloading https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:04:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:04:35 INFO - Downloaded 61727877 bytes. 20:04:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2 20:04:35 INFO - mkdir: /builds/slave/test/properties 20:04:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:04:35 INFO - Writing to file /builds/slave/test/properties/build_url 20:04:35 INFO - Contents: 20:04:35 INFO - build_url:https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2 20:04:35 INFO - Running post-action listener: _resource_record_post_action 20:04:35 INFO - Running post-action listener: set_extra_try_arguments 20:04:35 INFO - [mozharness: 2017-02-16 04:04:35.864567Z] Finished download-and-extract step (success) 20:04:35 INFO - [mozharness: 2017-02-16 04:04:35.864664Z] Running populate-webroot step. 20:04:35 INFO - Running pre-action listener: _resource_record_pre_action 20:04:35 INFO - Running main action method: populate_webroot 20:04:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:04:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:04:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:04:35 INFO - '--tppagecycles', 20:04:35 INFO - '7'], 20:04:35 INFO - 'tests': ['tsvgm']}}, 20:04:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:04:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:04:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:04:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:04:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:04:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:04:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['damp', 'tps']}, 20:04:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'tests': ['damp', 'tps']}, 20:04:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['dromaeo_dom']}, 20:04:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:04:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['basic_compositor_video']}, 20:04:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:04:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['a11yr', 20:04:35 INFO - 'ts_paint', 20:04:35 INFO - 'tpaint', 20:04:35 INFO - 'sessionrestore', 20:04:35 INFO - 'sessionrestore_no_auto_restore', 20:04:35 INFO - 'tabpaint']}, 20:04:35 INFO - 'other-e10s': {'tests': ['a11yr', 20:04:35 INFO - 'ts_paint', 20:04:35 INFO - 'tpaint', 20:04:35 INFO - 'sessionrestore', 20:04:35 INFO - 'sessionrestore_no_auto_restore', 20:04:35 INFO - 'tabpaint']}, 20:04:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:04:35 INFO - 'ts_paint', 20:04:35 INFO - 'tpaint', 20:04:35 INFO - 'sessionrestore', 20:04:35 INFO - 'sessionrestore_no_auto_restore', 20:04:35 INFO - 'tabpaint']}, 20:04:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:04:35 INFO - 'ts_paint', 20:04:35 INFO - 'tpaint', 20:04:35 INFO - 'sessionrestore', 20:04:35 INFO - 'sessionrestore_no_auto_restore', 20:04:35 INFO - 'tabpaint']}, 20:04:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['a11yr', 20:04:35 INFO - 'ts_paint', 20:04:35 INFO - 'tpaint', 20:04:35 INFO - 'sessionrestore', 20:04:35 INFO - 'sessionrestore_no_auto_restore', 20:04:35 INFO - 'tabpaint']}, 20:04:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['a11yr', 20:04:35 INFO - 'ts_paint', 20:04:35 INFO - 'tpaint', 20:04:35 INFO - 'sessionrestore', 20:04:35 INFO - 'sessionrestore_no_auto_restore', 20:04:35 INFO - 'tabpaint']}, 20:04:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['tsvgx', 20:04:35 INFO - 'tsvgr_opacity', 20:04:35 INFO - 'tart', 20:04:35 INFO - 'tscrollx', 20:04:35 INFO - 'cart', 20:04:35 INFO - 'tsvg_static']}, 20:04:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:04:35 INFO - 'tsvgr_opacity', 20:04:35 INFO - 'tart', 20:04:35 INFO - 'tscrollx', 20:04:35 INFO - 'cart', 20:04:35 INFO - 'tsvg_static']}, 20:04:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'talos_options': ['--disable-e10s'], 20:04:35 INFO - 'tests': ['tp5o']}, 20:04:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:04:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'talos_options': ['--disable-e10s', 20:04:35 INFO - '--xperf_path', 20:04:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:04:35 INFO - 'tests': ['tp5n']}, 20:04:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:04:35 INFO - 'talos_options': ['--xperf_path', 20:04:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:04:35 INFO - 'tests': ['tp5n']}}, 20:04:35 INFO - 'talos.zip': {'path': '', 20:04:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:04:35 INFO - Running post-action listener: _resource_record_post_action 20:04:35 INFO - [mozharness: 2017-02-16 04:04:35.877783Z] Finished populate-webroot step (success) 20:04:35 INFO - [mozharness: 2017-02-16 04:04:35.877876Z] Running create-virtualenv step. 20:04:35 INFO - Running pre-action listener: _resource_record_pre_action 20:04:35 INFO - Running main action method: create_virtualenv 20:04:35 INFO - Creating virtualenv /builds/slave/test/build/venv 20:04:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:04:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:04:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:04:35 INFO - Using real prefix '/usr' 20:04:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:04:36 INFO - Installing distribute.............................................................................................................................................................................................done. 20:04:38 INFO - Installing pip.................done. 20:04:38 INFO - Return code: 0 20:04:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:04:38 INFO - Reading from file tmpfile_stdout 20:04:38 INFO - Output received: 20:04:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:04:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:04:38 INFO - Using env: {'DISPLAY': ':0', 20:04:38 INFO - 'HOME': '/home/cltbld', 20:04:38 INFO - 'LANG': 'en_US.UTF-8', 20:04:38 INFO - 'LANGUAGE': 'en_US:en', 20:04:38 INFO - 'LOGNAME': 'cltbld', 20:04:38 INFO - 'MAIL': '/var/mail/cltbld', 20:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:38 INFO - 'MOZ_NO_REMOTE': '1', 20:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:38 INFO - 'NO_EM_RESTART': '1', 20:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:38 INFO - 'PWD': '/builds/slave/test', 20:04:38 INFO - 'SHELL': '/bin/bash', 20:04:38 INFO - 'SHLVL': '1', 20:04:38 INFO - 'TERM': 'linux', 20:04:38 INFO - 'TMOUT': '86400', 20:04:38 INFO - 'USER': 'cltbld', 20:04:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:38 INFO - '_': '/tools/buildbot/bin/python'} 20:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:04:38 INFO - Cleaning up... 20:04:38 INFO - Return code: 0 20:04:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:04:38 INFO - Using env: {'DISPLAY': ':0', 20:04:38 INFO - 'HOME': '/home/cltbld', 20:04:38 INFO - 'LANG': 'en_US.UTF-8', 20:04:38 INFO - 'LANGUAGE': 'en_US:en', 20:04:38 INFO - 'LOGNAME': 'cltbld', 20:04:38 INFO - 'MAIL': '/var/mail/cltbld', 20:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:38 INFO - 'MOZ_NO_REMOTE': '1', 20:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:38 INFO - 'NO_EM_RESTART': '1', 20:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:38 INFO - 'PWD': '/builds/slave/test', 20:04:38 INFO - 'SHELL': '/bin/bash', 20:04:38 INFO - 'SHLVL': '1', 20:04:38 INFO - 'TERM': 'linux', 20:04:38 INFO - 'TMOUT': '86400', 20:04:38 INFO - 'USER': 'cltbld', 20:04:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:38 INFO - '_': '/tools/buildbot/bin/python'} 20:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:39 INFO - Downloading/unpacking psutil>=3.1.1 20:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:04:41 INFO - Installing collected packages: psutil 20:04:41 INFO - Running setup.py install for psutil 20:04:41 INFO - building 'psutil._psutil_linux' extension 20:04:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:04:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:04:41 INFO - building 'psutil._psutil_posix' extension 20:04:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:04:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:04:41 INFO - Successfully installed psutil 20:04:41 INFO - Cleaning up... 20:04:41 INFO - Return code: 0 20:04:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:04:41 INFO - Using env: {'DISPLAY': ':0', 20:04:41 INFO - 'HOME': '/home/cltbld', 20:04:41 INFO - 'LANG': 'en_US.UTF-8', 20:04:41 INFO - 'LANGUAGE': 'en_US:en', 20:04:41 INFO - 'LOGNAME': 'cltbld', 20:04:41 INFO - 'MAIL': '/var/mail/cltbld', 20:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:41 INFO - 'MOZ_NO_REMOTE': '1', 20:04:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:41 INFO - 'NO_EM_RESTART': '1', 20:04:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:41 INFO - 'PWD': '/builds/slave/test', 20:04:41 INFO - 'SHELL': '/bin/bash', 20:04:41 INFO - 'SHLVL': '1', 20:04:41 INFO - 'TERM': 'linux', 20:04:41 INFO - 'TMOUT': '86400', 20:04:41 INFO - 'USER': 'cltbld', 20:04:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:41 INFO - '_': '/tools/buildbot/bin/python'} 20:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:04:44 INFO - Installing collected packages: mozsystemmonitor 20:04:44 INFO - Running setup.py install for mozsystemmonitor 20:04:44 INFO - Successfully installed mozsystemmonitor 20:04:44 INFO - Cleaning up... 20:04:44 INFO - Return code: 0 20:04:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:04:44 INFO - Using env: {'DISPLAY': ':0', 20:04:44 INFO - 'HOME': '/home/cltbld', 20:04:44 INFO - 'LANG': 'en_US.UTF-8', 20:04:44 INFO - 'LANGUAGE': 'en_US:en', 20:04:44 INFO - 'LOGNAME': 'cltbld', 20:04:44 INFO - 'MAIL': '/var/mail/cltbld', 20:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:44 INFO - 'MOZ_NO_REMOTE': '1', 20:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:44 INFO - 'NO_EM_RESTART': '1', 20:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:44 INFO - 'PWD': '/builds/slave/test', 20:04:44 INFO - 'SHELL': '/bin/bash', 20:04:44 INFO - 'SHLVL': '1', 20:04:44 INFO - 'TERM': 'linux', 20:04:44 INFO - 'TMOUT': '86400', 20:04:44 INFO - 'USER': 'cltbld', 20:04:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:44 INFO - '_': '/tools/buildbot/bin/python'} 20:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:44 INFO - Downloading/unpacking jsonschema==2.5.1 20:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:04:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:46 INFO - Downloading functools32-3.2.3-2.tar.gz 20:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:04:46 INFO - warning: no files found matching '*.txt' 20:04:46 INFO - no previously-included directories found matching 'build' 20:04:46 INFO - no previously-included directories found matching 'dist' 20:04:46 INFO - no previously-included directories found matching '.git*' 20:04:46 INFO - Installing collected packages: jsonschema, functools32 20:04:46 INFO - Running setup.py install for functools32 20:04:46 INFO - warning: no files found matching '*.txt' 20:04:46 INFO - no previously-included directories found matching 'build' 20:04:46 INFO - no previously-included directories found matching 'dist' 20:04:46 INFO - no previously-included directories found matching '.git*' 20:04:46 INFO - Successfully installed jsonschema functools32 20:04:46 INFO - Cleaning up... 20:04:46 INFO - Return code: 0 20:04:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:04:46 INFO - Using env: {'DISPLAY': ':0', 20:04:46 INFO - 'HOME': '/home/cltbld', 20:04:46 INFO - 'LANG': 'en_US.UTF-8', 20:04:46 INFO - 'LANGUAGE': 'en_US:en', 20:04:46 INFO - 'LOGNAME': 'cltbld', 20:04:46 INFO - 'MAIL': '/var/mail/cltbld', 20:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:46 INFO - 'MOZ_NO_REMOTE': '1', 20:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:46 INFO - 'NO_EM_RESTART': '1', 20:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:46 INFO - 'PWD': '/builds/slave/test', 20:04:46 INFO - 'SHELL': '/bin/bash', 20:04:46 INFO - 'SHLVL': '1', 20:04:46 INFO - 'TERM': 'linux', 20:04:46 INFO - 'TMOUT': '86400', 20:04:46 INFO - 'USER': 'cltbld', 20:04:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:46 INFO - '_': '/tools/buildbot/bin/python'} 20:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:04:47 INFO - Cleaning up... 20:04:47 INFO - Return code: 0 20:04:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:04:47 INFO - Using env: {'DISPLAY': ':0', 20:04:47 INFO - 'HOME': '/home/cltbld', 20:04:47 INFO - 'LANG': 'en_US.UTF-8', 20:04:47 INFO - 'LANGUAGE': 'en_US:en', 20:04:47 INFO - 'LOGNAME': 'cltbld', 20:04:47 INFO - 'MAIL': '/var/mail/cltbld', 20:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:47 INFO - 'MOZ_NO_REMOTE': '1', 20:04:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:47 INFO - 'NO_EM_RESTART': '1', 20:04:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:47 INFO - 'PWD': '/builds/slave/test', 20:04:47 INFO - 'SHELL': '/bin/bash', 20:04:47 INFO - 'SHLVL': '1', 20:04:47 INFO - 'TERM': 'linux', 20:04:47 INFO - 'TMOUT': '86400', 20:04:47 INFO - 'USER': 'cltbld', 20:04:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:47 INFO - '_': '/tools/buildbot/bin/python'} 20:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:47 INFO - Downloading/unpacking blobuploader==1.2.4 20:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:49 INFO - Downloading blobuploader-1.2.4.tar.gz 20:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:04:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:04:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:50 INFO - Downloading docopt-0.6.1.tar.gz 20:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:04:50 INFO - Installing collected packages: blobuploader, requests, docopt 20:04:50 INFO - Running setup.py install for blobuploader 20:04:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:04:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:04:50 INFO - Running setup.py install for requests 20:04:50 INFO - Running setup.py install for docopt 20:04:50 INFO - Successfully installed blobuploader requests docopt 20:04:50 INFO - Cleaning up... 20:04:50 INFO - Return code: 0 20:04:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:04:50 INFO - Using env: {'DISPLAY': ':0', 20:04:50 INFO - 'HOME': '/home/cltbld', 20:04:50 INFO - 'LANG': 'en_US.UTF-8', 20:04:50 INFO - 'LANGUAGE': 'en_US:en', 20:04:50 INFO - 'LOGNAME': 'cltbld', 20:04:50 INFO - 'MAIL': '/var/mail/cltbld', 20:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:50 INFO - 'MOZ_NO_REMOTE': '1', 20:04:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:50 INFO - 'NO_EM_RESTART': '1', 20:04:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:50 INFO - 'PWD': '/builds/slave/test', 20:04:50 INFO - 'SHELL': '/bin/bash', 20:04:50 INFO - 'SHLVL': '1', 20:04:50 INFO - 'TERM': 'linux', 20:04:50 INFO - 'TMOUT': '86400', 20:04:50 INFO - 'USER': 'cltbld', 20:04:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:50 INFO - '_': '/tools/buildbot/bin/python'} 20:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:04:51 INFO - Running setup.py (path:/tmp/pip-jGAv4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:04:51 INFO - Running setup.py (path:/tmp/pip-LcisFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:04:51 INFO - Running setup.py (path:/tmp/pip-_wuEia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:04:51 INFO - Running setup.py (path:/tmp/pip-OK88T3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:04:51 INFO - Running setup.py (path:/tmp/pip-2pifVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:04:51 INFO - Running setup.py (path:/tmp/pip-UkdR0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:04:51 INFO - Running setup.py (path:/tmp/pip-bGYkFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:04:51 INFO - Running setup.py (path:/tmp/pip-ZhMr4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:04:51 INFO - Running setup.py (path:/tmp/pip-JQWyEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:04:51 INFO - Running setup.py (path:/tmp/pip-pYOMfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:04:51 INFO - Running setup.py (path:/tmp/pip-ODKXII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:04:51 INFO - Running setup.py (path:/tmp/pip-SFk7uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:04:51 INFO - Running setup.py (path:/tmp/pip-SqJSsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:04:51 INFO - Running setup.py (path:/tmp/pip-FHMOHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:52 INFO - Running setup.py (path:/tmp/pip-finc01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:04:52 INFO - Running setup.py (path:/tmp/pip-mhx_Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:04:52 INFO - Running setup.py (path:/tmp/pip-x18goD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:04:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:04:52 INFO - Running setup.py install for manifestparser 20:04:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:04:52 INFO - Running setup.py install for mozcrash 20:04:52 INFO - Running setup.py install for mozdebug 20:04:52 INFO - Running setup.py install for mozdevice 20:04:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:04:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:04:52 INFO - Running setup.py install for mozfile 20:04:52 INFO - Running setup.py install for mozhttpd 20:04:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:04:52 INFO - Running setup.py install for mozinfo 20:04:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:04:52 INFO - Running setup.py install for mozInstall 20:04:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Running setup.py install for mozleak 20:04:53 INFO - Running setup.py install for mozlog 20:04:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Running setup.py install for moznetwork 20:04:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Running setup.py install for mozprocess 20:04:53 INFO - Running setup.py install for mozprofile 20:04:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Running setup.py install for mozrunner 20:04:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:04:53 INFO - Running setup.py install for mozscreenshot 20:04:53 INFO - Running setup.py install for moztest 20:04:53 INFO - Running setup.py install for mozversion 20:04:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:04:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:04:54 INFO - Cleaning up... 20:04:54 INFO - Return code: 0 20:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:04:54 INFO - Using env: {'DISPLAY': ':0', 20:04:54 INFO - 'HOME': '/home/cltbld', 20:04:54 INFO - 'LANG': 'en_US.UTF-8', 20:04:54 INFO - 'LANGUAGE': 'en_US:en', 20:04:54 INFO - 'LOGNAME': 'cltbld', 20:04:54 INFO - 'MAIL': '/var/mail/cltbld', 20:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:54 INFO - 'MOZ_NO_REMOTE': '1', 20:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:54 INFO - 'NO_EM_RESTART': '1', 20:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:54 INFO - 'PWD': '/builds/slave/test', 20:04:54 INFO - 'SHELL': '/bin/bash', 20:04:54 INFO - 'SHLVL': '1', 20:04:54 INFO - 'TERM': 'linux', 20:04:54 INFO - 'TMOUT': '86400', 20:04:54 INFO - 'USER': 'cltbld', 20:04:54 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:54 INFO - '_': '/tools/buildbot/bin/python'} 20:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:04:54 INFO - Running setup.py (path:/tmp/pip-qSXf27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:04:54 INFO - Running setup.py (path:/tmp/pip-gKQj3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:04:54 INFO - Running setup.py (path:/tmp/pip-RmCxc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:04:54 INFO - Running setup.py (path:/tmp/pip-VhGmsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:04:54 INFO - Running setup.py (path:/tmp/pip-T0FVYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:04:54 INFO - Running setup.py (path:/tmp/pip-BQNMaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:04:54 INFO - Running setup.py (path:/tmp/pip-znpB5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:04:54 INFO - Running setup.py (path:/tmp/pip-tiH6z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:04:54 INFO - Running setup.py (path:/tmp/pip-9AzQ3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:04:54 INFO - Running setup.py (path:/tmp/pip-dcCT9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:04:55 INFO - Running setup.py (path:/tmp/pip-uMJAmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:04:55 INFO - Running setup.py (path:/tmp/pip-Ua0HsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:04:55 INFO - Running setup.py (path:/tmp/pip-2OTYle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:04:55 INFO - Running setup.py (path:/tmp/pip-JehHHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:55 INFO - Running setup.py (path:/tmp/pip-Z_RjMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:04:55 INFO - Running setup.py (path:/tmp/pip-T8AuTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:04:55 INFO - Running setup.py (path:/tmp/pip-vZUXlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:04:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:57 INFO - Downloading blessings-1.6.tar.gz 20:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:04:57 INFO - Installing collected packages: blessings 20:04:57 INFO - Running setup.py install for blessings 20:04:57 INFO - Successfully installed blessings 20:04:57 INFO - Cleaning up... 20:04:57 INFO - Return code: 0 20:04:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:04:57 INFO - Reading from file tmpfile_stdout 20:04:57 INFO - Current package versions: 20:04:57 INFO - argparse == 1.2.1 20:04:57 INFO - blessings == 1.6 20:04:57 INFO - blobuploader == 1.2.4 20:04:57 INFO - docopt == 0.6.1 20:04:57 INFO - functools32 == 3.2.3-2 20:04:57 INFO - jsonschema == 2.5.1 20:04:57 INFO - manifestparser == 1.1 20:04:57 INFO - mozInstall == 1.12 20:04:57 INFO - mozcrash == 1.0 20:04:57 INFO - mozdebug == 0.1 20:04:57 INFO - mozdevice == 0.49 20:04:57 INFO - mozfile == 1.2 20:04:57 INFO - mozhttpd == 0.7 20:04:57 INFO - mozinfo == 0.9 20:04:57 INFO - mozleak == 0.1 20:04:57 INFO - mozlog == 3.4 20:04:57 INFO - moznetwork == 0.27 20:04:57 INFO - mozprocess == 0.24 20:04:57 INFO - mozprofile == 0.28 20:04:57 INFO - mozrunner == 6.13 20:04:57 INFO - mozscreenshot == 0.1 20:04:57 INFO - mozsystemmonitor == 0.3 20:04:57 INFO - moztest == 0.8 20:04:57 INFO - mozversion == 1.4 20:04:57 INFO - psutil == 3.1.1 20:04:57 INFO - requests == 1.2.3 20:04:57 INFO - wsgiref == 0.1.2 20:04:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:04:57 INFO - Using env: {'DISPLAY': ':0', 20:04:57 INFO - 'HOME': '/home/cltbld', 20:04:57 INFO - 'LANG': 'en_US.UTF-8', 20:04:57 INFO - 'LANGUAGE': 'en_US:en', 20:04:57 INFO - 'LOGNAME': 'cltbld', 20:04:57 INFO - 'MAIL': '/var/mail/cltbld', 20:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:57 INFO - 'MOZ_NO_REMOTE': '1', 20:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:57 INFO - 'NO_EM_RESTART': '1', 20:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:57 INFO - 'PWD': '/builds/slave/test', 20:04:57 INFO - 'SHELL': '/bin/bash', 20:04:57 INFO - 'SHLVL': '1', 20:04:57 INFO - 'TERM': 'linux', 20:04:57 INFO - 'TMOUT': '86400', 20:04:57 INFO - 'USER': 'cltbld', 20:04:57 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:57 INFO - '_': '/tools/buildbot/bin/python'} 20:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:04:58 INFO - Cleaning up... 20:04:58 INFO - Return code: 0 20:04:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cbbed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:04:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:04:58 INFO - Using env: {'DISPLAY': ':0', 20:04:58 INFO - 'HOME': '/home/cltbld', 20:04:58 INFO - 'LANG': 'en_US.UTF-8', 20:04:58 INFO - 'LANGUAGE': 'en_US:en', 20:04:58 INFO - 'LOGNAME': 'cltbld', 20:04:58 INFO - 'MAIL': '/var/mail/cltbld', 20:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:58 INFO - 'MOZ_NO_REMOTE': '1', 20:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:04:58 INFO - 'NO_EM_RESTART': '1', 20:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:58 INFO - 'PWD': '/builds/slave/test', 20:04:58 INFO - 'SHELL': '/bin/bash', 20:04:58 INFO - 'SHLVL': '1', 20:04:58 INFO - 'TERM': 'linux', 20:04:58 INFO - 'TMOUT': '86400', 20:04:58 INFO - 'USER': 'cltbld', 20:04:58 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:58 INFO - '_': '/tools/buildbot/bin/python'} 20:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:04:58 INFO - Cleaning up... 20:04:58 INFO - Return code: 0 20:04:58 INFO - Running post-action listener: _resource_record_post_action 20:04:58 INFO - Running post-action listener: _start_resource_monitoring 20:04:58 INFO - Starting resource monitoring. 20:04:58 INFO - [mozharness: 2017-02-16 04:04:58.475510Z] Finished create-virtualenv step (success) 20:04:58 INFO - [mozharness: 2017-02-16 04:04:58.476095Z] Running install step. 20:04:58 INFO - Running pre-action listener: _resource_record_pre_action 20:04:58 INFO - Running main action method: install 20:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:04:58 INFO - Reading from file tmpfile_stdout 20:04:58 INFO - Detecting whether we're running mozinstall >=1.0... 20:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:04:58 INFO - Reading from file tmpfile_stdout 20:04:58 INFO - Output received: 20:04:58 INFO - Usage: mozinstall [options] installer 20:04:58 INFO - Options: 20:04:58 INFO - -h, --help show this help message and exit 20:04:58 INFO - -d DEST, --destination=DEST 20:04:58 INFO - Directory to install application into. [default: 20:04:58 INFO - "/builds/slave/test"] 20:04:58 INFO - --app=APP Application being installed. [default: firefox] 20:04:58 INFO - mkdir: /builds/slave/test/build/application 20:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:05:16 INFO - Reading from file tmpfile_stdout 20:05:16 INFO - Output received: 20:05:16 INFO - /builds/slave/test/build/application/firefox/firefox 20:05:16 INFO - Running post-action listener: _resource_record_post_action 20:05:16 INFO - [mozharness: 2017-02-16 04:05:16.366792Z] Finished install step (success) 20:05:16 INFO - [mozharness: 2017-02-16 04:05:16.367070Z] Running run-tests step. 20:05:16 INFO - Running pre-action listener: _resource_record_pre_action 20:05:16 INFO - Running main action method: run_tests 20:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:05:16 INFO - Python 2.7.3 20:05:16 INFO - Return code: 0 20:05:16 INFO - grabbing minidump binary from tooltool 20:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d45540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cb9f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cba400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:05:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:05:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:05:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:05:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:05:16 INFO - Return code: 0 20:05:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:05:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:05:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:05:16 INFO - ENV: RUST_BACKTRACE is now 1 20:05:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:05:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:05:16 INFO - Using env: {'DISPLAY': ':0', 20:05:16 INFO - 'HOME': '/home/cltbld', 20:05:16 INFO - 'LANG': 'en_US.UTF-8', 20:05:16 INFO - 'LANGUAGE': 'en_US:en', 20:05:16 INFO - 'LOGNAME': 'cltbld', 20:05:16 INFO - 'MAIL': '/var/mail/cltbld', 20:05:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:05:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:16 INFO - 'MOZ_NO_REMOTE': '1', 20:05:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:16 INFO - 'NO_EM_RESTART': '1', 20:05:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:16 INFO - 'PWD': '/builds/slave/test', 20:05:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:05:16 INFO - 'RUST_BACKTRACE': '1', 20:05:16 INFO - 'SHELL': '/bin/bash', 20:05:16 INFO - 'SHLVL': '1', 20:05:16 INFO - 'TERM': 'linux', 20:05:16 INFO - 'TMOUT': '86400', 20:05:16 INFO - 'USER': 'cltbld', 20:05:16 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668', 20:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:16 INFO - '_': '/tools/buildbot/bin/python'} 20:05:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:05:16 INFO - mozversion application_buildid: 20170216030736 20:05:16 INFO - mozversion application_changeset: a9ec72f82299250e6023988e238931bbca0ef7fa 20:05:16 INFO - mozversion application_display_name: Nightly 20:05:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:05:16 INFO - mozversion application_name: Firefox 20:05:16 INFO - mozversion application_remotingname: firefox 20:05:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:05:16 INFO - mozversion application_vendor: Mozilla 20:05:16 INFO - mozversion application_version: 54.0a1 20:05:16 INFO - mozversion platform_buildid: 20170216030736 20:05:16 INFO - mozversion platform_changeset: a9ec72f82299250e6023988e238931bbca0ef7fa 20:05:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:05:16 INFO - mozversion platform_version: 54.0a1 20:05:16 INFO - using testdate: 1487217916 20:05:16 INFO - actual date: 1487217916 20:05:16 INFO - starting webserver on 'localhost:34146' 20:05:16 INFO - SUITE-START | Running 2 tests 20:05:16 INFO - TEST-START | dromaeo_css 20:05:16 INFO - operating with platform_type : linux_ 20:05:16 INFO - Initialising browser for dromaeo_css test... 20:05:16 INFO - TEST-INFO | started process 20206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTU328Z/profile http://localhost:34146/getInfo.html) 20:05:19 INFO - PROCESS | 20206 | Iterating over profile /tmp/tmpTU328Z/profile 20:05:20 INFO - PROCESS | 20206 | __metrics Screen width/height:1600/1200 20:05:20 INFO - PROCESS | 20206 | colorDepth:24 20:05:20 INFO - PROCESS | 20206 | Browser inner width/height: 1024/697 20:05:20 INFO - PROCESS | 20206 | __metrics 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/pluginreg.dat = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/prefs.js = Wed Feb 15 2017 20:04:34 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/cache2 = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/places.sqlite-wal = Wed Feb 15 2017 20:05:20 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/places.sqlite = Wed Feb 15 2017 20:05:20 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/mimeTypes.rdf = Wed Feb 15 2017 20:05:20 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/safebrowsing = Wed Feb 15 2017 20:05:20 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/directoryLinks.json = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/secmod.db = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/localstore.rdf = Wed Feb 15 2017 20:04:34 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | Stat failure 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/frequencyCap.json = Wed Feb 15 2017 20:05:19 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/.parentlock = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/thumbnails = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/extensions.json = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/sessionCheckpoints.json = Wed Feb 15 2017 20:05:20 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/cookies.sqlite = Wed Feb 15 2017 20:05:19 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | Stat failure 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/startupCache = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/extensions.ini = Wed Feb 15 2017 20:05:17 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/crashes = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/bookmarkbackups = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/minidumps = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/user.js = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/blocklist.xml = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/gmp = Wed Feb 15 2017 20:05:17 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/extensions = Wed Feb 15 2017 20:05:17 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/key3.db = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/content-prefs.sqlite = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/addons.json = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/webappsstore.sqlite = Wed Feb 15 2017 20:05:20 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/places.sqlite-shm = Wed Feb 15 2017 20:05:18 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/permissions.sqlite = Wed Feb 15 2017 20:05:17 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/cert8.db = Wed Feb 15 2017 20:05:16 GMT-0800 (PST) 20:05:20 INFO - PROCESS | 20206 | No birth date. Using mtime. 20:05:20 INFO - PROCESS | 20206 | Using date: /tmp/tmpTU328Z/profile/compatibility.ini = Wed Feb 15 2017 20:05:17 GMT-0800 (PST) 20:05:21 INFO - PROCESS | 20206 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:05:21 INFO - PROCESS | 20206 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:05:22 INFO - TEST-INFO | 20206: exit 0 20:05:22 INFO - Browser initialized. 20:05:22 INFO - Running cycle 1/1 for dromaeo_css test... 20:05:22 INFO - TEST-INFO | started process 20289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTU328Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:05:34 INFO - PROCESS | 20289 | RSS: Main: 149610496 20:05:34 INFO - PROCESS | 20289 | 20:08:35 INFO - PROCESS | 20289 | Cycle 1(1): loaded http://localhost:34146/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34146/tests/dromaeo/cssquery-ext.html) 20:08:37 INFO - PROCESS | 20289 | RSS: Main: 309587968 20:08:37 INFO - PROCESS | 20289 | 20:10:24 INFO - PROCESS | 20289 | ************************* 20:10:24 INFO - PROCESS | 20289 | A coding exception was thrown and uncaught in a Task. 20:10:24 INFO - PROCESS | 20289 | 20:10:24 ERROR - PROCESS | 20289 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:10:24 INFO - PROCESS | 20289 | Full stack: 20:10:24 INFO - PROCESS | 20289 | ************************* 20:11:40 INFO - PROCESS | 20289 | Cycle 1(1): loaded http://localhost:34146/tests/dromaeo/cssquery-ext.html (next: http://localhost:34146/tests/dromaeo/cssquery-jquery.html) 20:11:40 INFO - PROCESS | 20289 | RSS: Main: 248532992 20:11:40 INFO - PROCESS | 20289 | 20:14:42 INFO - PROCESS | 20289 | Cycle 1(1): loaded http://localhost:34146/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34146/tests/dromaeo/cssquery-mootools.html) 20:14:43 INFO - PROCESS | 20289 | RSS: Main: 187133952 20:14:43 INFO - PROCESS | 20289 | 20:17:45 INFO - PROCESS | 20289 | Cycle 1(1): loaded http://localhost:34146/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34146/tests/dromaeo/cssquery-prototype.html) 20:17:45 INFO - PROCESS | 20289 | RSS: Main: 185896960 20:17:45 INFO - PROCESS | 20289 | 20:20:48 INFO - PROCESS | 20289 | Cycle 1(1): loaded http://localhost:34146/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34146/tests/dromaeo/cssquery-yui.html) 20:20:49 INFO - PROCESS | 20289 | RSS: Main: 187473920 20:20:49 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | Cycle 1(1): loaded http://localhost:34146/tests/dromaeo/cssquery-yui.html (next: http://localhost:34146/tests/dromaeo/cssquery-dojo.html) 20:23:51 INFO - PROCESS | 20289 | RSS: Main: 280768512 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | __start_tp_report 20:23:51 INFO - PROCESS | 20289 | _x_x_mozilla_page_load 20:23:51 INFO - PROCESS | 20289 | _x_x_mozilla_page_load_details 20:23:51 INFO - PROCESS | 20289 | |i|pagename|runs| 20:23:51 INFO - PROCESS | 20289 | |0;dojo.html;3126.3434678717467;3146.2606287522444;3151.543026261191;3159.494480883059;3167.9683203167974;5233.293505376773;5234.8691282717955;5313.707746073969;5346.198070289459;5355.6251062425645;5256.737163141846;5289.074411977898;5293.126634105368;5306.257124002643;5346.91979620306;4680.508546602618;4701.177293973541;4816.253480710496;4824.975875120637;4863.076015557051;4834.9033019339595;4876.97561512192;4898.730569818658;4898.975505122469;4909.7545122743895;4713.740744259065;4728.527147285261;4758.524147585248;4771.904561908743;4835;4773.880652983668;4779.3070004849105;4810.855674329776;4842.539958703917;4846.975765121152;4716.50476699945;4734;4765.904681906342;4766.38037055184;4773.16469617819;5206.5314121729225;5308.575313974873;5329.493698098674;5334.493223143834;5378.354597448292;5638.2670252866865;5647.237622920894;5716.857078573027;5725.112607545837;5732.570057245682;5763.596548241625;5829.679367634779;5866.648001119904;5867.295924489089;5867.882642347172;5415.512603865671;5421.349438067417;5424.674519528841;5427.701476418795;5432.945670543244;5814.360420353758;5919.082542205965;5919.822405327847;5924.7333869975955;5925.792597259076;5518.117101263779;5583;5613.410591887793;5628.324601047819;5712.714364281769;5724.856878577985;5735.397783232784;5743.483086522233;5745.741441635137;5756.539476841843;5636.633618814764;5707.286589176353;5711.286089238845;5714.228579141846;5716.085426331767;5616.522595579339;5665.5467562594895;5674.801381951612;5738.856528586819;5756.107803290497;4396.560343965642;4413.13943780966;4433.6009759121835;4435.756033418193;4454.621357184676;3926.332523471017;3957.940630890539;3969.563348031714;3988.022934381095;3997.0806714455257;5385.892282154335;5396.028714831289;5399.271098401744;5415;5441.047816632074;5648.181013753065;5668.348139963957;5711.9714401428555;5739.626924249994;5797.072468405035;5386.218998245311;5428.2671839301975;5446.1558458439;5457.508824205761;5458.099413596712;5002.699838009731;5004.224345226496;5010;5030.673006254655;5034.723090229999;6005.579609427386;6012.819615411545;6027.969860150672;6033.064874944391;6038.245219347582;5649.435056494318;5682.77268909239;5807.303123625192;5811.244538210006;5813.651180929158;5812.796552120655;5884.676342801187;5900.763969441175;5930.11048342752;5982.162497250301;5633.324001119892;5636.520895723909;5636.661800291996;5638.718064096861;5640.041192997282;5658.830235092953;5686.630369026;5719.656820590778;5731.054376027909;5734.3692193857805;5532.834014979557;5603.215549822947;5611.214429979887;5614.550835933034;5615.213870058276;5532.695701736443;5627.127795191752;5656.519195868397;5658.745356458887;5661.66030038199;5576.609637325349;5576.7211639417055;5577.581681373995;5579.553635709052;5619.662820230799;5581.6371935824045;5581.972090139686;5598.300212473441;5635.126555383922;5638.971805140948;5361.410244873139;5402.054640437829;5402.945970540244;5479.274028680953;5487.862803429946 20:23:51 INFO - PROCESS | 20289 | |1;ext.html;551.0738994730066;573.959822812403;598.0998597111349;598.5002522893384;602.9638221706698;32694.67305326946;33377.332453350915;33388.66611333889;42962;44448.11103777923;45939;46464.53535464645;46472;46525;46548.30177547331;9224.999999999984;9413.858792118124;9418.293627977895;9424.434533927986;9434.339596228267;7568.167501574822;7715.382769378436;8789.25291350236;8900.332475064397;10594.78810423791;10480.737981550446;10954.780904381907;10964.890351096506;10967.451627418597;10974.396408197546;10846.837297440545;10894.782104357948;10919.39943303118;10927.180461465421;10937;10756.892431075745;10809.78380432387;10831.079358254558;10833.241673082888;10840.457977101192;43169.56830431687;43516.12967740659;43530.78234608807;43560.56439435628;43612.345814812485;3614.5120408744747;3665.248624032067;3667.9816600917093;3669.6513831185994;3688.17016171362;29712.108636740933;30194.09417717472;30209.697903020908;30255.848720756254;30258.092257232318;4112.8149233284275;4216.599423054805;4287.678424118204;4467.910641787179;4476.216662084154;280284;310393;312953;313373.43313283514;314544;49636.751816240685;50029.74985125051;50449.49550504521;50495.62857100034;50558.74720626373;65848.67075664591;66193.33806661871;66248;66319;66364.6681766588;15101;15540.06759594428;15564.455244066403;15565.377384904703;15587;11880.40597970098;11927.582534611247;11975.64073077809;11984.520619175135;12002.21985570935;15878.126703031214;16165.272562734704;16247.67504649924;16340.18299085041;16637.584060398585;5898.410158984153;5910.0993344393355;5937.732802023919;6098.969505152446;6217.129601855744;858.7853036740814;872.7905302727299;881.9064360193314;887.4630848336792;891.7859713668673;30259.092227232777;30909.227269318;30927;30956.69043309583;31101.440740980768;957.2820384711466;1027.7379268286538;1036.3471013261596;1049.2130901823632;1059.750958524746;16167;16205.594860128354;16252.75620865688;16290.696744260431;16665.833341666428;417906;419622;420605;420738;421954;59821.40178598245;60461;60531;60579.394206058256;60745;1311.3377744239099;1345.105504839271;1376.291210026837;1391.3877893726728;1399.8040274361801;28527.714722852506;29317.706822931497;29842.850785745934;29917.850410747807;30557.23606909845;49772.50227497824;50949.49050509596;51458;51458.48541514537;51483.227751584505;333897;336484;338042;339000;340231;38979.805100974314;39745.60254397419;39930.60069399269;40213;40356;36973.26053478861;42183.578164219194;44258.77870610626;44718.329225061;46392.30411543762;38562.80718596389;39104.804475977435;39130.80434597809;40833.18333633251;41032.38451423291;39177.453465524646;39856.782972646724;40203.1959360817;40694.59305406908;41526.792366037975;34286.31427371489;34761.304773903874;34804.477932831534;35094.824525878226;35206.64793352136;33294.16764580905;34828;35183;35200;35629.82185089058;26007;26154.607680884423;26385.4722905537;26391.20826375212;26747.087564811238 20:23:51 INFO - PROCESS | 20289 | |2;jquery.html;421.3321884812572;431.1979717725032;432.9491970359633;435.15796932934774;445.2986546189752;18588.814111858894;18652.34716784913;20204.8989755051;20431.693524597107;20491.897540512295;20017.899910500462;20420.591588168227;20584.588308233826;20730.481737956557;20766.06552705128;6009.399060094;6121.296050954131;6165.136880836686;6170.537209709268;6174.6912654367325;5946.405359464041;6061.393860613947;6104.328523862371;6115.877682446348;6182.783602573911;6074.63552186868;6082.178905847721;6082.878342433149;6083.422074902899;6084.787032453865;5894.911576326359;6027.156198132243;6074.2103526541705;6080.087986802015;6080.756769729205;5400.513953744143;5425.6202065855405;5449.427810079964;5453.263809385722;5456.127019676833;21764.782352176593;22316.88841555782;23226;23481.76518234813;23675.526489470114;2979.8063125896747;3034.7723920706035;3042.6500952390315;3043.8630261638277;3048.4055609156003;9481.952590237004;9909.306348555603;10060.446675432848;10070.748231294203;10145.746356341077;1865.3005123078844;1887.160213704902;1898.810118988104;1903.1816318982833;1912.368918256972;81485.59257203735;83638.58180709058;85124.57437712772;85879.57060214721;85999.57000215021;10821.783564328669;11622.94188529052;11790.764184716258;11905.523779048914;12133.27200367963;17201.483955481355;17211.31154753796;17228.741568876478;17262.65474690499;17274.913625431793;8276.503409795432;8441.95779021101;8582.570871456403;8608.09614990417;8681.956590217134;7739.722945713894;7805.41459390548;7825.530468171928;7996.84006319882;8009.959950200328;4699.835505757282;4733.881652958635;5055.646104772631;5251.238570407269;5303.496167864047;1880.3324819689087;2111.2188490258704;2146.6565349544;2154.827613790893;2156.450105223158;1039.27770199712;1049.2550289294534;1053.3679792124665;1056.984145237822;1099.9890001099886;19318.517037073903;19326.710099348522;19361.709574356395;19377.4186774397;19723;955.7945041815904;1021.8109649714827;1027.8920713324994;1028.1466382902174;1037.2635428845454;9707.563159657691;9767.804643907082;10519.894801052045;10550.366977981346;10573.048425641575;569135;573273;576936;577427;577518;106597;107973;108514.97820261461;108925;109379.45310273398;1129.4465711801322;1138.681169272605;1175.629676651869;1176;1178.9233699809486;15580.298886549906;16200.432984845474;16564.585885352964;16566.66866662684;16756.916215418845;43205.60942033964;43356.34965475583;43757.78121109501;43917.12165756603;44992.55007449884;59625.70187149037;60658;61036.38963610307;61514.38955014301;63534.682326588074;7278.745243916437;8540.572971351532;8603.225709686158;8610.268127209258;9584.952075239578;9594.42433453995;13249.602511924657;14182.078164919247;14183.290835458392;14186;11329.830052549054;11556.364399958084;11751.177417580688;11893.554039145885;11901.821472677744;10690.465476726287;11531.365774882463;11743.060555155738;11946.761064778482;12366.072544559014;10655;11707.707307317385;12046.216995895238;12235.510579576718;12524.123311368481;7670.8849367260655;7821.296083352526;7850.764477065697;8083.232092951161;9206.217471514996;11493.310401375944;11584.884151158718;11725.472353743931;11766.941165294118;11808.76382472363 20:23:51 INFO - PROCESS | 20289 | |3;mootools.html;1117.6926345255058;1399.342309114716;1434.662854229256;1443.184600700604;1459.6350912271932;7544.170141284454;8300.211479909414;8706.434081784679;8720.912790872082;8727.258183054448;7755.612219389036;7873.015873015873;7911.8417631647335;8052.63763130659;8071.394645401604;1936.8353689936373;2212.8008479236823;2240.484688521641;2246.3934737620834;2253.808426283768;2019.7273368095339;2032.5629989552244;2225.1544413122992;2302.1942320187886;2303.0327262549686;2270.6707527408516;2272.943176420586;2274.533720587284;2276.1009401286483;2279.430142464384;2172.6632371982446;2185.584738899604;2192.495725983017;2199.516106456577;2203.6694495825595;2215.5458131083087;2258.5821622999797;2277.7949984501474;2279.7492275849645;2279.806216471602;8482.15178482153;8530.14698530142;8530.70142545008;8534.445261058074;8541.316694664474;4926.55660990509;5013.072581572421;5041.697498150123;5058.848234552969;5062.31658726071;2985.179075754185;3100.224943764059;3174.460341741886;3263.543103965447;3332.5334453176574;1266.9113162078659;1273.5797186928385;1344.69744307531;1367.2616786935043;1418.361737218256;11043.116550675926;11245.60640377591;11299.717507062389;11301.491432885457;11305.773884522263;4394.868153955386;4598.931016034762;4600.608948239437;4603.286490593963;4649.04694537619;9774.5601447935;9834.557444914995;9851.45816980073;9852.655157069468;9857.901420985841;5232.502912223333;5240.0567897778;5244.6328756987405;5246.816361427332;5255.868603284948;4610.3084537319;4646.651501137429;4759.143354196209;4805.831795887128;4822.662413631082;3493.94759078614;3604.729645276562;3825.8660946866726;3967.9603203968168;3974.9801250994137;1225.7609766095704;1268.308771719415;1283.4417028592406;1283.6020833541631;1298.7207750333723;819.7909533069029;828.7555171224503;832.0628182515729;852;853.0914575976565;5024.819167495709;5357.973210134002;5416.729163541886;5550.91673624896;5780.450857168562;964.6334392930642;966.4587830814626;972.2562239886493;977.6871401151706;1003.0972125087479;3409.3010932758725;3472.965270347315;3509.298140371885;3592.389293820057;3662.926741465156;9776;9930;9947;10189.133923616575;10247.897521024843;9398.060193980547;10367.533460994273;10393.740156496147;10399.376037437778;10427.739306517398;7716.498427602188;7965.681372745141;7970.2826745593175;7972.481788683833;7974.082980457206;5407.432219617041;5421.1055175895535;5478.506934375925;5503.669779813224;5674.319081710221;9112.453252804853;9185.402948808307;9305.581248843682;9354.158125768714;9367.484788336707;9385.296102792181;9551.283653725859;9647.758806029624;9677.467739274125;9787.36382135159;7095.82260443493;7108.786736397917;7234.74678386254;7240.275972402717;7252.746153884589;7822.295993360625;7882.054153501616;8012.5192488450875;8013.919860801318;8025.478343907627;6981.720731170696;7053.259407762111;7060.223375428596;7079.29207079288;7265.019222404908;6679.432248258952;6819.5908245505425;6866.553674011173;6872.62200578953;6872.896906546506;5618.52242559387;5664.830055098354;5723.141528770718;5801.6519008859605;5883.970580147072;4682.531746825291;4693.319468677091;4734.786934587883;4763.071201115749;4794.880127996828;4677.625789936729;4793.161196790478;4835.661503694708;4852.611791056636;5001 20:23:51 INFO - PROCESS | 20289 | |4;prototype.html;402.9204306397723;410.70839703810293;412.3340804600571;413.26645204761525;419.6349176216692;5253.553447956928;5283.867903302419;5292.179712144619;5312.681239125645;5315.574754019674;5062.21535661972;5068.214426763849;5184.1705327147665;5230.449508927239;5274.551663108631;4165.500139983191;4167.6874234432535;4169.061961058753;4174.812133454002;4185.560516145806;4169.937450938223;4195.538490766014;4212.92570394549;4213.810378532958;4223.91552168958;4083.9591604083876;4145.486897282501;4162.354835000565;4170.833166673329;4205;4182.832686692529;4187.623113919732;4189.245935731567;4191.841201781085;4210.852620158295;3794.7723136611617;3802.5056742623283;3805.676517496016;3806.543214814212;3817.560980487224;4996.076510543467;5188.610854185951;5191.299174611417;5193.195054766517;5215.295935048795;4887.144749668793;4900.852974410774;4902.215645496739;4919.409670839522;4927.704337739711;5550.139728342114;5559.277293951801;5564.582656300794;5567.359753628344;5609.52319052881;4134.772587507686;4152.480939882515;4201.508464495091;4232.682548808821;4260.893477663052;5419.512243898029;5588.55291576677;5716.256541754763;5736.3403208631125;5749.741261643194;4158.189153115113;4161.687873409446;4161.687873409506;4187.183499217624;4287.228298906227;4345.304751239786;4368.541303163186;4412.367424053121;4426.15902978433;4431.62331201845;4557.316402539645;4818.204996175592;4867.793563206939;4875.195592727231;4903.485134060944;5509.944900550943;5536.612437129363;5562.638428502134;5571.192177134293;5575.470330318694;1384.2801743093535;1386.4246337769914;1401.5795261421533;1417.3338530890464;1422.943082276718;927.8886533615874;957.5451660461225;966.6954909203579;983.7098056073476;995.3331268050424;5091.872703182376;5149.588032957355;5156.458571849902;5203.464977831549;5233.921491177635;5442.319710036246;5485;5505.559555235572;5518.475744804237;5546.473085056913;5021.698698078127;5158.45836187195;5177.301064356339;5186.325777648882;5187.299714538564;2908.665503467086;2918.9199996001616;2919.4307110113323;2923.2399576110365;2931.267183204199;5615.943840561542;5653.491185793298;5687.544996400278;5715.5427565794635;5724.398938111522;5292.9735351323;5584.329880414376;5604.262678569657;5613.691246981455;5635.1828984796975;5657.830265092054;5674.55993922609;5695.800646977336;5748.856278593069;5763.394843541368;5282.2604835323855;5494.692229263639;5508.614396992172;5559.694216818114;5602.075657516464;5449.209864569694;5475.780968761364;5512.503874651301;5529.806456773994;5573.414791446839;5561.582881283839;5694;5698.173764803997;5710.885782284414;5745.597808153389;3955.7452252215962;3969.5435024971343;4146.730462519927;4146.792660366909;4175.64507016907;5539.362973257966;5659.405762395054;5666.716664166693;5725.8568535786935;5736.913946290725;5456.972715136399;5458;5473.151661492475;5492.5605951524585;5492.697901615449;5478.479544443271;5482.91775623358;5491.890162196653;5501.697406642513;5535.806246781343;5242.606804489755;5471.6169868108855;5489.404440091746;5498.780048798163;5514.228008078792;5510;5535.39110697831;5535.806246781343;5544.3346798384455;5556.499915007668;5331.280277162688;5396.21754845553;5407.053765591085;5414.242006119225;5422.620416570802 20:23:51 INFO - PROCESS | 20289 | |5;yui.html;739.3567596191315;750.3247077630135;787.2206515549608;836.4186890111373;858.4377232912441;15739.60650983726;16535.338586456557;16536.007839529637;16685.41601043962;16712.74930876036;15025.248737563134;15056.096634201927;15083.396664133448;15137.24313784312;15183.848161518381;785.7996210966196;793.1949071692237;805.1626308639038;808.0424696734389;809.951402915824;802.0295442514564;806.3267171911461;806.8103995561039;808.1918081918081;809.0130041349544;850.6682393866397;853.6457370191363;853.7353420439683;854.7435769269225;854.9786255343604;847.2078606502939;849.8512760266959;850.1073872433919;850.2857599616322;850.5789634131082;619.4115590189339;623.3174673732256;623.688155922039;623.8970569855968;623.9594426362272;14777.556673299818;14795.630109247248;14955.102693838404;15859.920700396424;16567.337306507725;15521.767173492406;15766.132862692548;15766.527004189893;16013.439529616408;16270.674586508203;11373.76256066125;11509.309441433541;11822.054235661126;11954.125707476931;12092.637220883387;3750.13746838226;3915.138669492707;3920.9803950980063;4001.979990100031;4014.8394064237395;74006.25993740048;76591.61704191443;78095;78915.6054219731;79271;11246.7188320291;11387.20289579738;11395.316281023164;11493.597724079616;11768.35274059935;22609.321720348416;22709.318720438412;22738.20416285422;22794.772052279266;22795.31614051581;7779.57212353326;7792.142864284924;7824.647890844925;7874.448788584744;7959.761207163794;4044;4122.896927576775;4133.132042271157;4156.085661154541;4208.747475151439;3381.9154521136675;3432.7768695034742;3436.4845273209216;3437.106352348407;3453.9481907771406;581.6655423131716;582.6773224779727;585.2713371852071;591.7130191856946;593.7328202308892;709.5813470052693;735.569691730333;743.8470370925045;745.5265906149525;746.2574738135481;9405.670801521914;9548.18840398574;9630.4703241321;9720.27097967641;10339.483025848678;726.6738202779842;754.5246494708299;765.4182821056038;774.9263819937097;777.564563844249;4590.1737687215955;4922.680025798311;4930.186519224288;4949.208126699711;4967.950320496821;71718.22199116186;73133;73312;74117;75703;55118.17322740162;59261.40738592645;61927.6903615479;62286;62929.37070629235;56150;56660.43339566552;56853;57221.71389143028;60507;10912.508937098008;11085.760969194584;11110.166737494881;11203.831942521032;11448.599299024494;37647.24705505929;37741.4338784913;37856.24287514179;40347.394789078775;40640;45993;47727.28409073797;49437.25844112269;51190;51452;13919.652008699864;13932;14046.719065618834;14119.505817296345;14327.78508322355;13949.58151255464;13963.629106693104;14019.29903504841;14032.158070515801;14305.21321327337;13077.934610326887;13108.541201057917;13177.802332964822;13359.732805343645;13421.597352079454;12902.483900643869;12953.417096230503;13399.12905661129;13493.527726529524;14034.578962631138;12981.935090324489;13252;13590.524331648345;14046.227457489527;14058.78911816344;10518.631847885288;10918.727031824268;10944.39805810688;11287.830682539932;11707.239029463057;9374.531273436438;10973;11153.163512736415;11476.254043487146;11485.712857178638 20:23:51 INFO - PROCESS | 20289 | __end_tp_report 20:23:51 INFO - PROCESS | 20289 | __start_cc_report 20:23:51 INFO - PROCESS | 20289 | _x_x_mozilla_cycle_collect,1653 20:23:51 INFO - PROCESS | 20289 | __end_cc_report 20:23:51 INFO - PROCESS | 20289 | __startTimestamp1487219031933__endTimestamp 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | ------- Summary: start ------- 20:23:51 INFO - PROCESS | 20289 | Number of tests: 6 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | [#0] dojo.html Cycles:165 Average:5317.23 Median:5532.83 stddev:603.23 (10.9%) stddev-sans-first:580.07 20:23:51 INFO - PROCESS | 20289 | Values: 3126.3 3146.3 3151.5 3159.5 3168.0 5233.3 5234.9 5313.7 5346.2 5355.6 5256.7 5289.1 5293.1 5306.3 5346.9 4680.5 4701.2 4816.3 4825.0 4863.1 4834.9 4877.0 4898.7 4899.0 4909.8 4713.7 4728.5 4758.5 4771.9 4835.0 4773.9 4779.3 4810.9 4842.5 4847.0 4716.5 4734.0 4765.9 4766.4 4773.2 5206.5 5308.6 5329.5 5334.5 5378.4 5638.3 5647.2 5716.9 5725.1 5732.6 5763.6 5829.7 5866.6 5867.3 5867.9 5415.5 5421.3 5424.7 5427.7 5432.9 5814.4 5919.1 5919.8 5924.7 5925.8 5518.1 5583.0 5613.4 5628.3 5712.7 5724.9 5735.4 5743.5 5745.7 5756.5 5636.6 5707.3 5711.3 5714.2 5716.1 5616.5 5665.5 5674.8 5738.9 5756.1 4396.6 4413.1 4433.6 4435.8 4454.6 3926.3 3957.9 3969.6 3988.0 3997.1 5385.9 5396.0 5399.3 5415.0 5441.0 5648.2 5668.3 5712.0 5739.6 5797.1 5386.2 5428.3 5446.2 5457.5 5458.1 5002.7 5004.2 5010.0 5030.7 5034.7 6005.6 6012.8 6028.0 6033.1 6038.2 5649.4 5682.8 5807.3 5811.2 5813.7 5812.8 5884.7 5900.8 5930.1 5982.2 5633.3 5636.5 5636.7 5638.7 5640.0 5658.8 5686.6 5719.7 5731.1 5734.4 5532.8 5603.2 5611.2 5614.6 5615.2 5532.7 5627.1 5656.5 5658.7 5661.7 5576.6 5576.7 5577.6 5579.6 5619.7 5581.6 5582.0 5598.3 5635.1 5639.0 5361.4 5402.1 5402.9 5479.3 5487.9 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | [#1] ext.html Cycles:180 Average:52694.65 Median:3668.82 stddev:94120.24 (2565.4%) stddev-sans-first:94302.85 20:23:51 INFO - PROCESS | 20289 | Values: 551.1 574.0 598.1 598.5 603.0 32694.7 33377.3 33388.7 42962.0 44448.1 45939.0 46464.5 46472.0 46525.0 46548.3 9225.0 9413.9 9418.3 9424.4 9434.3 7568.2 7715.4 8789.3 8900.3 10594.8 10480.7 10954.8 10964.9 10967.5 10974.4 10846.8 10894.8 10919.4 10927.2 10937.0 10756.9 10809.8 10831.1 10833.2 10840.5 43169.6 43516.1 43530.8 43560.6 43612.3 3614.5 3665.2 3668.0 3669.7 3688.2 29712.1 30194.1 30209.7 30255.8 30258.1 4112.8 4216.6 4287.7 4467.9 4476.2 280284.0 310393.0 312953.0 313373.4 314544.0 49636.8 50029.7 50449.5 50495.6 50558.7 65848.7 66193.3 66248.0 66319.0 66364.7 15101.0 15540.1 15564.5 15565.4 15587.0 11880.4 11927.6 11975.6 11984.5 12002.2 15878.1 16165.3 16247.7 16340.2 16637.6 5898.4 5910.1 5937.7 6099.0 6217.1 858.8 872.8 881.9 887.5 891.8 30259.1 30909.2 30927.0 30956.7 31101.4 957.3 1027.7 1036.3 1049.2 1059.8 16167.0 16205.6 16252.8 16290.7 16665.8 417906.0 419622.0 420605.0 420738.0 421954.0 59821.4 60461.0 60531.0 60579.4 60745.0 1311.3 1345.1 1376.3 1391.4 1399.8 28527.7 29317.7 29842.9 29917.9 30557.2 49772.5 50949.5 51458.0 51458.5 51483.2 333897.0 336484.0 338042.0 339000.0 340231.0 38979.8 39745.6 39930.6 40213.0 40356.0 36973.3 42183.6 44258.8 44718.3 46392.3 38562.8 39104.8 39130.8 40833.2 41032.4 39177.5 39856.8 40203.2 40694.6 41526.8 34286.3 34761.3 34804.5 35094.8 35206.6 33294.2 34828.0 35183.0 35200.0 35629.8 26007.0 26154.6 26385.5 26391.2 26747.1 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | [#2] jquery.html Cycles:180 Average:32193.86 Median:22771.44 stddev:94769.61 (416.2%) stddev-sans-first:95005.43 20:23:51 INFO - PROCESS | 20289 | Values: 421.3 431.2 432.9 435.2 445.3 18588.8 18652.3 20204.9 20431.7 20491.9 20017.9 20420.6 20584.6 20730.5 20766.1 6009.4 6121.3 6165.1 6170.5 6174.7 5946.4 6061.4 6104.3 6115.9 6182.8 6074.6 6082.2 6082.9 6083.4 6084.8 5894.9 6027.2 6074.2 6080.1 6080.8 5400.5 5425.6 5449.4 5453.3 5456.1 21764.8 22316.9 23226.0 23481.8 23675.5 2979.8 3034.8 3042.7 3043.9 3048.4 9482.0 9909.3 10060.4 10070.7 10145.7 1865.3 1887.2 1898.8 1903.2 1912.4 81485.6 83638.6 85124.6 85879.6 85999.6 10821.8 11622.9 11790.8 11905.5 12133.3 17201.5 17211.3 17228.7 17262.7 17274.9 8276.5 8442.0 8582.6 8608.1 8682.0 7739.7 7805.4 7825.5 7996.8 8010.0 4699.8 4733.9 5055.6 5251.2 5303.5 1880.3 2111.2 2146.7 2154.8 2156.5 1039.3 1049.3 1053.4 1057.0 1100.0 19318.5 19326.7 19361.7 19377.4 19723.0 955.8 1021.8 1027.9 1028.1 1037.3 9707.6 9767.8 10519.9 10550.4 10573.0 569135.0 573273.0 576936.0 577427.0 577518.0 106597.0 107973.0 108515.0 108925.0 109379.5 1129.4 1138.7 1175.6 1176.0 1178.9 15580.3 16200.4 16564.6 16566.7 16756.9 43205.6 43356.3 43757.8 43917.1 44992.6 59625.7 60658.0 61036.4 61514.4 63534.7 7278.7 8540.6 8603.2 8610.3 9585.0 9594.4 13249.6 14182.1 14183.3 14186.0 11329.8 11556.4 11751.2 11893.6 11901.8 10690.5 11531.4 11743.1 11946.8 12366.1 10655.0 11707.7 12046.2 12235.5 12524.1 7670.9 7821.3 7850.8 8083.2 9206.2 11493.3 11584.9 11725.5 11766.9 11808.8 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | [#3] mootools.html Cycles:180 Average:5420.14 Median:5050.27 stddev:3044.07 (60.3%) stddev-sans-first:3035.43 20:23:51 INFO - PROCESS | 20289 | Values: 1117.7 1399.3 1434.7 1443.2 1459.6 7544.2 8300.2 8706.4 8720.9 8727.3 7755.6 7873.0 7911.8 8052.6 8071.4 1936.8 2212.8 2240.5 2246.4 2253.8 2019.7 2032.6 2225.2 2302.2 2303.0 2270.7 2272.9 2274.5 2276.1 2279.4 2172.7 2185.6 2192.5 2199.5 2203.7 2215.5 2258.6 2277.8 2279.7 2279.8 8482.2 8530.1 8530.7 8534.4 8541.3 4926.6 5013.1 5041.7 5058.8 5062.3 2985.2 3100.2 3174.5 3263.5 3332.5 1266.9 1273.6 1344.7 1367.3 1418.4 11043.1 11245.6 11299.7 11301.5 11305.8 4394.9 4598.9 4600.6 4603.3 4649.0 9774.6 9834.6 9851.5 9852.7 9857.9 5232.5 5240.1 5244.6 5246.8 5255.9 4610.3 4646.7 4759.1 4805.8 4822.7 3493.9 3604.7 3825.9 3968.0 3975.0 1225.8 1268.3 1283.4 1283.6 1298.7 819.8 828.8 832.1 852.0 853.1 5024.8 5358.0 5416.7 5550.9 5780.5 964.6 966.5 972.3 977.7 1003.1 3409.3 3473.0 3509.3 3592.4 3662.9 9776.0 9930.0 9947.0 10189.1 10247.9 9398.1 10367.5 10393.7 10399.4 10427.7 7716.5 7965.7 7970.3 7972.5 7974.1 5407.4 5421.1 5478.5 5503.7 5674.3 9112.5 9185.4 9305.6 9354.2 9367.5 9385.3 9551.3 9647.8 9677.5 9787.4 7095.8 7108.8 7234.7 7240.3 7252.7 7822.3 7882.1 8012.5 8013.9 8025.5 6981.7 7053.3 7060.2 7079.3 7265.0 6679.4 6819.6 6866.6 6872.6 6872.9 5618.5 5664.8 5723.1 5801.7 5884.0 4682.5 4693.3 4734.8 4763.1 4794.9 4677.6 4793.2 4835.7 4852.6 5001.0 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | [#4] prototype.html Cycles:180 Average:4656.04 Median:5264.05 stddev:1320.13 (25.1%) stddev-sans-first:1284.65 20:23:51 INFO - PROCESS | 20289 | Values: 402.9 410.7 412.3 413.3 419.6 5253.6 5283.9 5292.2 5312.7 5315.6 5062.2 5068.2 5184.2 5230.4 5274.6 4165.5 4167.7 4169.1 4174.8 4185.6 4169.9 4195.5 4212.9 4213.8 4223.9 4084.0 4145.5 4162.4 4170.8 4205.0 4182.8 4187.6 4189.2 4191.8 4210.9 3794.8 3802.5 3805.7 3806.5 3817.6 4996.1 5188.6 5191.3 5193.2 5215.3 4887.1 4900.9 4902.2 4919.4 4927.7 5550.1 5559.3 5564.6 5567.4 5609.5 4134.8 4152.5 4201.5 4232.7 4260.9 5419.5 5588.6 5716.3 5736.3 5749.7 4158.2 4161.7 4161.7 4187.2 4287.2 4345.3 4368.5 4412.4 4426.2 4431.6 4557.3 4818.2 4867.8 4875.2 4903.5 5509.9 5536.6 5562.6 5571.2 5575.5 1384.3 1386.4 1401.6 1417.3 1422.9 927.9 957.5 966.7 983.7 995.3 5091.9 5149.6 5156.5 5203.5 5233.9 5442.3 5485.0 5505.6 5518.5 5546.5 5021.7 5158.5 5177.3 5186.3 5187.3 2908.7 2918.9 2919.4 2923.2 2931.3 5615.9 5653.5 5687.5 5715.5 5724.4 5293.0 5584.3 5604.3 5613.7 5635.2 5657.8 5674.6 5695.8 5748.9 5763.4 5282.3 5494.7 5508.6 5559.7 5602.1 5449.2 5475.8 5512.5 5529.8 5573.4 5561.6 5694.0 5698.2 5710.9 5745.6 3955.7 3969.5 4146.7 4146.8 4175.6 5539.4 5659.4 5666.7 5725.9 5736.9 5457.0 5458.0 5473.2 5492.6 5492.7 5478.5 5482.9 5491.9 5501.7 5535.8 5242.6 5471.6 5489.4 5498.8 5514.2 5510.0 5535.4 5535.8 5544.3 5556.5 5331.3 5396.2 5407.1 5414.2 5422.6 20:23:51 INFO - PROCESS | 20289 | 20:23:51 INFO - PROCESS | 20289 | [#5] yui.html Cycles:180 Average:16879.22 Median:22381.45 stddev:20699.21 (92.5%) stddev-sans-first:20721.79 20:23:51 INFO - PROCESS | 20289 | Values: 739.4 750.3 787.2 836.4 858.4 15739.6 16535.3 16536.0 16685.4 16712.7 15025.2 15056.1 15083.4 15137.2 15183.8 785.8 793.2 805.2 808.0 810.0 802.0 806.3 806.8 808.2 809.0 850.7 853.6 853.7 854.7 855.0 847.2 849.9 850.1 850.3 850.6 619.4 623.3 623.7 623.9 624.0 14777.6 14795.6 14955.1 15859.9 16567.3 15521.8 15766.1 15766.5 16013.4 16270.7 11373.8 11509.3 11822.1 11954.1 12092.6 3750.1 3915.1 3921.0 4002.0 4014.8 74006.3 76591.6 78095.0 78915.6 79271.0 11246.7 11387.2 11395.3 11493.6 11768.4 22609.3 22709.3 22738.2 22794.8 22795.3 7779.6 7792.1 7824.6 7874.4 7959.8 4044.0 4122.9 4133.1 4156.1 4208.7 3381.9 3432.8 3436.5 3437.1 3453.9 581.7 582.7 585.3 591.7 593.7 709.6 735.6 743.8 745.5 746.3 9405.7 9548.2 9630.5 9720.3 10339.5 726.7 754.5 765.4 774.9 777.6 4590.2 4922.7 4930.2 4949.2 4968.0 71718.2 73133.0 73312.0 74117.0 75703.0 55118.2 59261.4 61927.7 62286.0 62929.4 56150.0 56660.4 56853.0 57221.7 60507.0 10912.5 11085.8 11110.2 11203.8 11448.6 37647.2 37741.4 37856.2 40347.4 40640.0 45993.0 47727.3 49437.3 51190.0 51452.0 13919.7 13932.0 14046.7 14119.5 14327.8 13949.6 13963.6 14019.3 14032.2 14305.2 13077.9 13108.5 13177.8 13359.7 13421.6 12902.5 12953.4 13399.1 13493.5 14034.6 12981.9 13252.0 13590.5 14046.2 14058.8 10518.6 10918.7 10944.4 11287.8 11707.2 9374.5 10973.0 11153.2 11476.3 11485.7 20:23:51 INFO - PROCESS | 20289 | -------- Summary: end -------- 20:23:51 INFO - PROCESS | 20289 | 20:23:52 INFO - TEST-INFO | 20289: exit 0 20:23:52 INFO - TEST-OK | dromaeo_css | took 1116087ms 20:23:52 INFO - TEST-START | kraken 20:23:52 INFO - operating with platform_type : linux_ 20:23:52 INFO - Initialising browser for kraken test... 20:23:52 INFO - TEST-INFO | started process 20598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpywZDxQ/profile http://localhost:34146/getInfo.html) 20:23:55 INFO - PROCESS | 20598 | Iterating over profile /tmp/tmpywZDxQ/profile 20:23:56 INFO - PROCESS | 20598 | __metrics Screen width/height:1600/1200 20:23:56 INFO - PROCESS | 20598 | colorDepth:24 20:23:56 INFO - PROCESS | 20598 | Browser inner width/height: 1024/697 20:23:56 INFO - PROCESS | 20598 | __metrics 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/pluginreg.dat = Wed Feb 15 2017 20:23:54 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/prefs.js = Wed Feb 15 2017 20:04:34 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/cache2 = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/places.sqlite-wal = Wed Feb 15 2017 20:23:56 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/places.sqlite = Wed Feb 15 2017 20:23:56 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/safebrowsing = Wed Feb 15 2017 20:23:56 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/directoryLinks.json = Wed Feb 15 2017 20:23:55 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/secmod.db = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/localstore.rdf = Wed Feb 15 2017 20:04:34 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | Stat failure 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/frequencyCap.json = Wed Feb 15 2017 20:23:55 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/.parentlock = Wed Feb 15 2017 20:23:52 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/thumbnails = Wed Feb 15 2017 20:23:54 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/extensions.json = Wed Feb 15 2017 20:23:55 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/sessionCheckpoints.json = Wed Feb 15 2017 20:23:56 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/startupCache = Wed Feb 15 2017 20:23:52 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/extensions.ini = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/crashes = Wed Feb 15 2017 20:23:52 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/bookmarkbackups = Wed Feb 15 2017 20:23:54 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/minidumps = Wed Feb 15 2017 20:23:52 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/user.js = Wed Feb 15 2017 20:23:52 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/blocklist.xml = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/gmp = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/extensions = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/key3.db = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/content-prefs.sqlite = Wed Feb 15 2017 20:23:54 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/addons.json = Wed Feb 15 2017 20:23:55 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/places.sqlite-shm = Wed Feb 15 2017 20:23:54 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/permissions.sqlite = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/cert8.db = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:56 INFO - PROCESS | 20598 | No birth date. Using mtime. 20:23:56 INFO - PROCESS | 20598 | Using date: /tmp/tmpywZDxQ/profile/compatibility.ini = Wed Feb 15 2017 20:23:53 GMT-0800 (PST) 20:23:57 INFO - PROCESS | 20598 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:23:57 INFO - PROCESS | 20598 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:23:58 INFO - TEST-INFO | 20598: exit 0 20:23:58 INFO - Browser initialized. 20:23:58 INFO - Running cycle 1/1 for kraken test... 20:23:58 INFO - TEST-INFO | started process 20685 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpywZDxQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:10 INFO - PROCESS | 20685 | RSS: Main: 151187456 20:24:10 INFO - PROCESS | 20685 | 20:24:12 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34146/tests/kraken/driver.html?testName=audio-beat-detection) 20:24:13 INFO - PROCESS | 20685 | RSS: Main: 206270464 20:24:13 INFO - PROCESS | 20685 | 20:24:16 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34146/tests/kraken/driver.html?testName=audio-dft) 20:24:16 INFO - PROCESS | 20685 | RSS: Main: 185245696 20:24:16 INFO - PROCESS | 20685 | 20:24:19 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34146/tests/kraken/driver.html?testName=audio-fft) 20:24:20 INFO - PROCESS | 20685 | RSS: Main: 267517952 20:24:20 INFO - PROCESS | 20685 | 20:24:22 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34146/tests/kraken/driver.html?testName=audio-oscillator) 20:24:23 INFO - PROCESS | 20685 | RSS: Main: 178753536 20:24:23 INFO - PROCESS | 20685 | 20:24:25 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34146/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:24:25 INFO - PROCESS | 20685 | RSS: Main: 182009856 20:24:25 INFO - PROCESS | 20685 | 20:24:28 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34146/tests/kraken/driver.html?testName=imaging-darkroom) 20:24:29 INFO - PROCESS | 20685 | RSS: Main: 221442048 20:24:29 INFO - PROCESS | 20685 | 20:24:32 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34146/tests/kraken/driver.html?testName=imaging-desaturate) 20:24:33 INFO - PROCESS | 20685 | RSS: Main: 221310976 20:24:33 INFO - PROCESS | 20685 | 20:24:36 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34146/tests/kraken/driver.html?testName=json-parse-financial) 20:24:36 INFO - PROCESS | 20685 | RSS: Main: 219967488 20:24:36 INFO - PROCESS | 20685 | 20:24:38 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34146/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:24:39 INFO - PROCESS | 20685 | RSS: Main: 182042624 20:24:39 INFO - PROCESS | 20685 | 20:24:40 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:24:41 INFO - PROCESS | 20685 | RSS: Main: 190242816 20:24:41 INFO - PROCESS | 20685 | 20:24:43 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:24:44 INFO - PROCESS | 20685 | RSS: Main: 198320128 20:24:44 INFO - PROCESS | 20685 | 20:24:46 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:24:47 INFO - PROCESS | 20685 | RSS: Main: 208379904 20:24:47 INFO - PROCESS | 20685 | 20:24:50 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:24:51 INFO - PROCESS | 20685 | RSS: Main: 203735040 20:24:51 INFO - PROCESS | 20685 | 20:24:52 INFO - PROCESS | 20685 | Cycle 1(1): loaded http://localhost:34146/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34146/tests/kraken/driver.html?testName=ai-astar) 20:24:53 INFO - PROCESS | 20685 | RSS: Main: 256544768 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | __start_tp_report 20:24:53 INFO - PROCESS | 20685 | _x_x_mozilla_page_load 20:24:53 INFO - PROCESS | 20685 | _x_x_mozilla_page_load_details 20:24:53 INFO - PROCESS | 20685 | |i|pagename|runs| 20:24:53 INFO - PROCESS | 20685 | |0;ai-astar;110;113;114;114;113;113;113;113;112;113 20:24:53 INFO - PROCESS | 20685 | |1;audio-beat-detection;145;126;120;155;123;127;120;123;148;120 20:24:53 INFO - PROCESS | 20685 | |2;audio-dft;162;161;162;160;160;161;161;161;161;161 20:24:53 INFO - PROCESS | 20685 | |3;audio-fft;75;75;75;107;77;78;78;78;97;77 20:24:53 INFO - PROCESS | 20685 | |4;audio-oscillator;83;84;111;83;83;84;102;82;84;112 20:24:53 INFO - PROCESS | 20685 | |5;imaging-gaussian-blur;113;113;113;113;114;113;113;113;114;115 20:24:53 INFO - PROCESS | 20685 | |6;imaging-darkroom;147;149;148;148;147;147;146;147;147;147 20:24:53 INFO - PROCESS | 20685 | |7;imaging-desaturate;96;95;96;95;95;96;96;96;96;99 20:24:53 INFO - PROCESS | 20685 | |8;json-parse-financial;57;55;56;56;57;56;56;55;55;58 20:24:53 INFO - PROCESS | 20685 | |9;json-stringify-tinderbox;63;64;63;63;67;62;66;62;62;62 20:24:53 INFO - PROCESS | 20685 | |10;stanford-crypto-aes;85;88;85;84;84;85;84;84;85;84 20:24:53 INFO - PROCESS | 20685 | |11;stanford-crypto-ccm;132;133;131;135;133;132;133;131;169;162 20:24:53 INFO - PROCESS | 20685 | |12;stanford-crypto-pbkdf2;177;175;178;178;172;178;176;179;178;171 20:24:53 INFO - PROCESS | 20685 | |13;stanford-crypto-sha256-iterative;65;65;65;64;68;79;63;64;64;64 20:24:53 INFO - PROCESS | 20685 | __end_tp_report 20:24:53 INFO - PROCESS | 20685 | __start_cc_report 20:24:53 INFO - PROCESS | 20685 | _x_x_mozilla_cycle_collect,1665 20:24:53 INFO - PROCESS | 20685 | __end_cc_report 20:24:53 INFO - PROCESS | 20685 | __startTimestamp1487219093046__endTimestamp 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | ------- Summary: start ------- 20:24:53 INFO - PROCESS | 20685 | Number of tests: 14 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.14 (1.0%) stddev-sans-first:0.60 20:24:53 INFO - PROCESS | 20685 | Values: 110.0 113.0 114.0 114.0 113.0 113.0 113.0 113.0 112.0 113.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:126.50 stddev:13.30 (10.5%) stddev-sans-first:13.06 20:24:53 INFO - PROCESS | 20685 | Values: 145.0 126.0 120.0 155.0 123.0 127.0 120.0 123.0 148.0 120.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.60 20:24:53 INFO - PROCESS | 20685 | Values: 162.0 161.0 162.0 160.0 160.0 161.0 161.0 161.0 161.0 161.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#3] audio-fft Cycles:10 Average:81.70 Median:77.50 stddev:11.03 (14.2%) stddev-sans-first:11.42 20:24:53 INFO - PROCESS | 20685 | Values: 75.0 75.0 75.0 107.0 77.0 78.0 78.0 78.0 97.0 77.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:83.00 stddev:12.39 (14.9%) stddev-sans-first:12.82 20:24:53 INFO - PROCESS | 20685 | Values: 83.0 84.0 111.0 83.0 83.0 84.0 102.0 82.0 84.0 112.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 20:24:53 INFO - PROCESS | 20685 | Values: 113.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 114.0 115.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 20:24:53 INFO - PROCESS | 20685 | Values: 147.0 149.0 148.0 148.0 147.0 147.0 146.0 147.0 147.0 147.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:1.15 (1.2%) stddev-sans-first:1.22 20:24:53 INFO - PROCESS | 20685 | Values: 96.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 99.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 20:24:53 INFO - PROCESS | 20685 | Values: 57.0 55.0 56.0 56.0 57.0 56.0 56.0 55.0 55.0 58.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:63.00 stddev:1.78 (2.8%) stddev-sans-first:1.88 20:24:53 INFO - PROCESS | 20685 | Values: 63.0 64.0 63.0 63.0 67.0 62.0 66.0 62.0 62.0 62.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 20:24:53 INFO - PROCESS | 20685 | Values: 85.0 88.0 85.0 84.0 84.0 85.0 84.0 84.0 85.0 84.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:133.00 stddev:14.06 (10.6%) stddev-sans-first:14.68 20:24:53 INFO - PROCESS | 20685 | Values: 132.0 133.0 131.0 135.0 133.0 132.0 133.0 131.0 169.0 162.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:178.00 stddev:2.74 (1.5%) stddev-sans-first:2.89 20:24:53 INFO - PROCESS | 20685 | Values: 177.0 175.0 178.0 178.0 172.0 178.0 176.0 179.0 178.0 171.0 20:24:53 INFO - PROCESS | 20685 | 20:24:53 INFO - PROCESS | 20685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:65.00 stddev:4.72 (7.3%) stddev-sans-first:4.99 20:24:53 INFO - PROCESS | 20685 | Values: 65.0 65.0 65.0 64.0 68.0 79.0 63.0 64.0 64.0 64.0 20:24:53 INFO - PROCESS | 20685 | -------- Summary: end -------- 20:24:53 INFO - PROCESS | 20685 | 20:24:54 INFO - TEST-INFO | 20685: exit 0 20:24:54 INFO - TEST-OK | kraken | took 61400ms 20:24:54 INFO - SUITE-END | took 1177s 20:24:54 INFO - Completed test suite (00:19:37) 20:24:54 INFO - javascript benchmark 20:24:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7139.98494550195, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.3434678717467, 3146.2606287522444, 3151.543026261191, 3159.494480883059, 3167.9683203167974, 5233.293505376773, 5234.8691282717955, 5313.707746073969, 5346.198070289459, 5355.6251062425645, 5256.737163141846, 5289.074411977898, 5293.126634105368, 5306.257124002643, 5346.91979620306, 4680.508546602618, 4701.177293973541, 4816.253480710496, 4824.975875120637, 4863.076015557051, 4834.9033019339595, 4876.97561512192, 4898.730569818658, 4898.975505122469, 4909.7545122743895, 4713.740744259065, 4728.527147285261, 4758.524147585248, 4771.904561908743, 4835.0, 4773.880652983668, 4779.3070004849105, 4810.855674329776, 4842.539958703917, 4846.975765121152, 4716.50476699945, 4734.0, 4765.904681906342, 4766.38037055184, 4773.16469617819, 5206.5314121729225, 5308.575313974873, 5329.493698098674, 5334.493223143834, 5378.354597448292, 5638.2670252866865, 5647.237622920894, 5716.857078573027, 5725.112607545837, 5732.570057245682, 5763.596548241625, 5829.679367634779, 5866.648001119904, 5867.295924489089, 5867.882642347172, 5415.512603865671, 5421.349438067417, 5424.674519528841, 5427.701476418795, 5432.945670543244, 5814.360420353758, 5919.082542205965, 5919.822405327847, 5924.7333869975955, 5925.792597259076, 5518.117101263779, 5583.0, 5613.410591887793, 5628.324601047819, 5712.714364281769, 5724.856878577985, 5735.397783232784, 5743.483086522233, 5745.741441635137, 5756.539476841843, 5636.633618814764, 5707.286589176353, 5711.286089238845, 5714.228579141846, 5716.085426331767, 5616.522595579339, 5665.5467562594895, 5674.801381951612, 5738.856528586819, 5756.107803290497, 4396.560343965642, 4413.13943780966, 4433.6009759121835, 4435.756033418193, 4454.621357184676, 3926.332523471017, 3957.940630890539, 3969.563348031714, 3988.022934381095, 3997.0806714455257, 5385.892282154335, 5396.028714831289, 5399.271098401744, 5415.0, 5441.047816632074, 5648.181013753065, 5668.348139963957, 5711.9714401428555, 5739.626924249994, 5797.072468405035, 5386.218998245311, 5428.2671839301975, 5446.1558458439, 5457.508824205761, 5458.099413596712, 5002.699838009731, 5004.224345226496, 5010.0, 5030.673006254655, 5034.723090229999, 6005.579609427386, 6012.819615411545, 6027.969860150672, 6033.064874944391, 6038.245219347582, 5649.435056494318, 5682.77268909239, 5807.303123625192, 5811.244538210006, 5813.651180929158, 5812.796552120655, 5884.676342801187, 5900.763969441175, 5930.11048342752, 5982.162497250301, 5633.324001119892, 5636.520895723909, 5636.661800291996, 5638.718064096861, 5640.041192997282, 5658.830235092953, 5686.630369026, 5719.656820590778, 5731.054376027909, 5734.3692193857805, 5532.834014979557, 5603.215549822947, 5611.214429979887, 5614.550835933034, 5615.213870058276, 5532.695701736443, 5627.127795191752, 5656.519195868397, 5658.745356458887, 5661.66030038199, 5576.609637325349, 5576.7211639417055, 5577.581681373995, 5579.553635709052, 5619.662820230799, 5581.6371935824045, 5581.972090139686, 5598.300212473441, 5635.126555383922, 5638.971805140948, 5361.410244873139, 5402.054640437829, 5402.945970540244, 5479.274028680953, 5487.862803429946], "value": 5277.056746059539, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.0738994730066, 573.959822812403, 598.0998597111349, 598.5002522893384, 602.9638221706698, 32694.67305326946, 33377.332453350915, 33388.66611333889, 42962.0, 44448.11103777923, 45939.0, 46464.53535464645, 46472.0, 46525.0, 46548.30177547331, 9224.999999999984, 9413.858792118124, 9418.293627977895, 9424.434533927986, 9434.339596228267, 7568.167501574822, 7715.382769378436, 8789.25291350236, 8900.332475064397, 10594.78810423791, 10480.737981550446, 10954.780904381907, 10964.890351096506, 10967.451627418597, 10974.396408197546, 10846.837297440545, 10894.782104357948, 10919.39943303118, 10927.180461465421, 10937.0, 10756.892431075745, 10809.78380432387, 10831.079358254558, 10833.241673082888, 10840.457977101192, 43169.56830431687, 43516.12967740659, 43530.78234608807, 43560.56439435628, 43612.345814812485, 3614.5120408744747, 3665.248624032067, 3667.9816600917093, 3669.6513831185994, 3688.17016171362, 29712.108636740933, 30194.09417717472, 30209.697903020908, 30255.848720756254, 30258.092257232318, 4112.8149233284275, 4216.599423054805, 4287.678424118204, 4467.910641787179, 4476.216662084154, 280284.0, 310393.0, 312953.0, 313373.43313283514, 314544.0, 49636.751816240685, 50029.74985125051, 50449.49550504521, 50495.62857100034, 50558.74720626373, 65848.67075664591, 66193.33806661871, 66248.0, 66319.0, 66364.6681766588, 15101.0, 15540.06759594428, 15564.455244066403, 15565.377384904703, 15587.0, 11880.40597970098, 11927.582534611247, 11975.64073077809, 11984.520619175135, 12002.21985570935, 15878.126703031214, 16165.272562734704, 16247.67504649924, 16340.18299085041, 16637.584060398585, 5898.410158984153, 5910.0993344393355, 5937.732802023919, 6098.969505152446, 6217.129601855744, 858.7853036740814, 872.7905302727299, 881.9064360193314, 887.4630848336792, 891.7859713668673, 30259.092227232777, 30909.227269318, 30927.0, 30956.69043309583, 31101.440740980768, 957.2820384711466, 1027.7379268286538, 1036.3471013261596, 1049.2130901823632, 1059.750958524746, 16167.0, 16205.594860128354, 16252.75620865688, 16290.696744260431, 16665.833341666428, 417906.0, 419622.0, 420605.0, 420738.0, 421954.0, 59821.40178598245, 60461.0, 60531.0, 60579.394206058256, 60745.0, 1311.3377744239099, 1345.105504839271, 1376.291210026837, 1391.3877893726728, 1399.8040274361801, 28527.714722852506, 29317.706822931497, 29842.850785745934, 29917.850410747807, 30557.23606909845, 49772.50227497824, 50949.49050509596, 51458.0, 51458.48541514537, 51483.227751584505, 333897.0, 336484.0, 338042.0, 339000.0, 340231.0, 38979.805100974314, 39745.60254397419, 39930.60069399269, 40213.0, 40356.0, 36973.26053478861, 42183.578164219194, 44258.77870610626, 44718.329225061, 46392.30411543762, 38562.80718596389, 39104.804475977435, 39130.80434597809, 40833.18333633251, 41032.38451423291, 39177.453465524646, 39856.782972646724, 40203.1959360817, 40694.59305406908, 41526.792366037975, 34286.31427371489, 34761.304773903874, 34804.477932831534, 35094.824525878226, 35206.64793352136, 33294.16764580905, 34828.0, 35183.0, 35200.0, 35629.82185089058, 26007.0, 26154.607680884423, 26385.4722905537, 26391.20826375212, 26747.087564811238], "value": 19516.928785552063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.3321884812572, 431.1979717725032, 432.9491970359633, 435.15796932934774, 445.2986546189752, 18588.814111858894, 18652.34716784913, 20204.8989755051, 20431.693524597107, 20491.897540512295, 20017.899910500462, 20420.591588168227, 20584.588308233826, 20730.481737956557, 20766.06552705128, 6009.399060094, 6121.296050954131, 6165.136880836686, 6170.537209709268, 6174.6912654367325, 5946.405359464041, 6061.393860613947, 6104.328523862371, 6115.877682446348, 6182.783602573911, 6074.63552186868, 6082.178905847721, 6082.878342433149, 6083.422074902899, 6084.787032453865, 5894.911576326359, 6027.156198132243, 6074.2103526541705, 6080.087986802015, 6080.756769729205, 5400.513953744143, 5425.6202065855405, 5449.427810079964, 5453.263809385722, 5456.127019676833, 21764.782352176593, 22316.88841555782, 23226.0, 23481.76518234813, 23675.526489470114, 2979.8063125896747, 3034.7723920706035, 3042.6500952390315, 3043.8630261638277, 3048.4055609156003, 9481.952590237004, 9909.306348555603, 10060.446675432848, 10070.748231294203, 10145.746356341077, 1865.3005123078844, 1887.160213704902, 1898.810118988104, 1903.1816318982833, 1912.368918256972, 81485.59257203735, 83638.58180709058, 85124.57437712772, 85879.57060214721, 85999.57000215021, 10821.783564328669, 11622.94188529052, 11790.764184716258, 11905.523779048914, 12133.27200367963, 17201.483955481355, 17211.31154753796, 17228.741568876478, 17262.65474690499, 17274.913625431793, 8276.503409795432, 8441.95779021101, 8582.570871456403, 8608.09614990417, 8681.956590217134, 7739.722945713894, 7805.41459390548, 7825.530468171928, 7996.84006319882, 8009.959950200328, 4699.835505757282, 4733.881652958635, 5055.646104772631, 5251.238570407269, 5303.496167864047, 1880.3324819689087, 2111.2188490258704, 2146.6565349544, 2154.827613790893, 2156.450105223158, 1039.27770199712, 1049.2550289294534, 1053.3679792124665, 1056.984145237822, 1099.9890001099886, 19318.517037073903, 19326.710099348522, 19361.709574356395, 19377.4186774397, 19723.0, 955.7945041815904, 1021.8109649714827, 1027.8920713324994, 1028.1466382902174, 1037.2635428845454, 9707.563159657691, 9767.804643907082, 10519.894801052045, 10550.366977981346, 10573.048425641575, 569135.0, 573273.0, 576936.0, 577427.0, 577518.0, 106597.0, 107973.0, 108514.97820261461, 108925.0, 109379.45310273398, 1129.4465711801322, 1138.681169272605, 1175.629676651869, 1176.0, 1178.9233699809486, 15580.298886549906, 16200.432984845474, 16564.585885352964, 16566.66866662684, 16756.916215418845, 43205.60942033964, 43356.34965475583, 43757.78121109501, 43917.12165756603, 44992.55007449884, 59625.70187149037, 60658.0, 61036.38963610307, 61514.38955014301, 63534.682326588074, 7278.745243916437, 8540.572971351532, 8603.225709686158, 8610.268127209258, 9584.952075239578, 9594.42433453995, 13249.602511924657, 14182.078164919247, 14183.290835458392, 14186.0, 11329.830052549054, 11556.364399958084, 11751.177417580688, 11893.554039145885, 11901.821472677744, 10690.465476726287, 11531.365774882463, 11743.060555155738, 11946.761064778482, 12366.072544559014, 10655.0, 11707.707307317385, 12046.216995895238, 12235.510579576718, 12524.123311368481, 7670.8849367260655, 7821.296083352526, 7850.764477065697, 8083.232092951161, 9206.217471514996, 11493.310401375944, 11584.884151158718, 11725.472353743931, 11766.941165294118, 11808.76382472363], "value": 9604.419740121208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1117.6926345255058, 1399.342309114716, 1434.662854229256, 1443.184600700604, 1459.6350912271932, 7544.170141284454, 8300.211479909414, 8706.434081784679, 8720.912790872082, 8727.258183054448, 7755.612219389036, 7873.015873015873, 7911.8417631647335, 8052.63763130659, 8071.394645401604, 1936.8353689936373, 2212.8008479236823, 2240.484688521641, 2246.3934737620834, 2253.808426283768, 2019.7273368095339, 2032.5629989552244, 2225.1544413122992, 2302.1942320187886, 2303.0327262549686, 2270.6707527408516, 2272.943176420586, 2274.533720587284, 2276.1009401286483, 2279.430142464384, 2172.6632371982446, 2185.584738899604, 2192.495725983017, 2199.516106456577, 2203.6694495825595, 2215.5458131083087, 2258.5821622999797, 2277.7949984501474, 2279.7492275849645, 2279.806216471602, 8482.15178482153, 8530.14698530142, 8530.70142545008, 8534.445261058074, 8541.316694664474, 4926.55660990509, 5013.072581572421, 5041.697498150123, 5058.848234552969, 5062.31658726071, 2985.179075754185, 3100.224943764059, 3174.460341741886, 3263.543103965447, 3332.5334453176574, 1266.9113162078659, 1273.5797186928385, 1344.69744307531, 1367.2616786935043, 1418.361737218256, 11043.116550675926, 11245.60640377591, 11299.717507062389, 11301.491432885457, 11305.773884522263, 4394.868153955386, 4598.931016034762, 4600.608948239437, 4603.286490593963, 4649.04694537619, 9774.5601447935, 9834.557444914995, 9851.45816980073, 9852.655157069468, 9857.901420985841, 5232.502912223333, 5240.0567897778, 5244.6328756987405, 5246.816361427332, 5255.868603284948, 4610.3084537319, 4646.651501137429, 4759.143354196209, 4805.831795887128, 4822.662413631082, 3493.94759078614, 3604.729645276562, 3825.8660946866726, 3967.9603203968168, 3974.9801250994137, 1225.7609766095704, 1268.308771719415, 1283.4417028592406, 1283.6020833541631, 1298.7207750333723, 819.7909533069029, 828.7555171224503, 832.0628182515729, 852.0, 853.0914575976565, 5024.819167495709, 5357.973210134002, 5416.729163541886, 5550.91673624896, 5780.450857168562, 964.6334392930642, 966.4587830814626, 972.2562239886493, 977.6871401151706, 1003.0972125087479, 3409.3010932758725, 3472.965270347315, 3509.298140371885, 3592.389293820057, 3662.926741465156, 9776.0, 9930.0, 9947.0, 10189.133923616575, 10247.897521024843, 9398.060193980547, 10367.533460994273, 10393.740156496147, 10399.376037437778, 10427.739306517398, 7716.498427602188, 7965.681372745141, 7970.2826745593175, 7972.481788683833, 7974.082980457206, 5407.432219617041, 5421.1055175895535, 5478.506934375925, 5503.669779813224, 5674.319081710221, 9112.453252804853, 9185.402948808307, 9305.581248843682, 9354.158125768714, 9367.484788336707, 9385.296102792181, 9551.283653725859, 9647.758806029624, 9677.467739274125, 9787.36382135159, 7095.82260443493, 7108.786736397917, 7234.74678386254, 7240.275972402717, 7252.746153884589, 7822.295993360625, 7882.054153501616, 8012.5192488450875, 8013.919860801318, 8025.478343907627, 6981.720731170696, 7053.259407762111, 7060.223375428596, 7079.29207079288, 7265.019222404908, 6679.432248258952, 6819.5908245505425, 6866.553674011173, 6872.62200578953, 6872.896906546506, 5618.52242559387, 5664.830055098354, 5723.141528770718, 5801.6519008859605, 5883.970580147072, 4682.531746825291, 4693.319468677091, 4734.786934587883, 4763.071201115749, 4794.880127996828, 4677.625789936729, 4793.161196790478, 4835.661503694708, 4852.611791056636, 5001.0], "value": 4375.951807231093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.9204306397723, 410.70839703810293, 412.3340804600571, 413.26645204761525, 419.6349176216692, 5253.553447956928, 5283.867903302419, 5292.179712144619, 5312.681239125645, 5315.574754019674, 5062.21535661972, 5068.214426763849, 5184.1705327147665, 5230.449508927239, 5274.551663108631, 4165.500139983191, 4167.6874234432535, 4169.061961058753, 4174.812133454002, 4185.560516145806, 4169.937450938223, 4195.538490766014, 4212.92570394549, 4213.810378532958, 4223.91552168958, 4083.9591604083876, 4145.486897282501, 4162.354835000565, 4170.833166673329, 4205.0, 4182.832686692529, 4187.623113919732, 4189.245935731567, 4191.841201781085, 4210.852620158295, 3794.7723136611617, 3802.5056742623283, 3805.676517496016, 3806.543214814212, 3817.560980487224, 4996.076510543467, 5188.610854185951, 5191.299174611417, 5193.195054766517, 5215.295935048795, 4887.144749668793, 4900.852974410774, 4902.215645496739, 4919.409670839522, 4927.704337739711, 5550.139728342114, 5559.277293951801, 5564.582656300794, 5567.359753628344, 5609.52319052881, 4134.772587507686, 4152.480939882515, 4201.508464495091, 4232.682548808821, 4260.893477663052, 5419.512243898029, 5588.55291576677, 5716.256541754763, 5736.3403208631125, 5749.741261643194, 4158.189153115113, 4161.687873409446, 4161.687873409506, 4187.183499217624, 4287.228298906227, 4345.304751239786, 4368.541303163186, 4412.367424053121, 4426.15902978433, 4431.62331201845, 4557.316402539645, 4818.204996175592, 4867.793563206939, 4875.195592727231, 4903.485134060944, 5509.944900550943, 5536.612437129363, 5562.638428502134, 5571.192177134293, 5575.470330318694, 1384.2801743093535, 1386.4246337769914, 1401.5795261421533, 1417.3338530890464, 1422.943082276718, 927.8886533615874, 957.5451660461225, 966.6954909203579, 983.7098056073476, 995.3331268050424, 5091.872703182376, 5149.588032957355, 5156.458571849902, 5203.464977831549, 5233.921491177635, 5442.319710036246, 5485.0, 5505.559555235572, 5518.475744804237, 5546.473085056913, 5021.698698078127, 5158.45836187195, 5177.301064356339, 5186.325777648882, 5187.299714538564, 2908.665503467086, 2918.9199996001616, 2919.4307110113323, 2923.2399576110365, 2931.267183204199, 5615.943840561542, 5653.491185793298, 5687.544996400278, 5715.5427565794635, 5724.398938111522, 5292.9735351323, 5584.329880414376, 5604.262678569657, 5613.691246981455, 5635.1828984796975, 5657.830265092054, 5674.55993922609, 5695.800646977336, 5748.856278593069, 5763.394843541368, 5282.2604835323855, 5494.692229263639, 5508.614396992172, 5559.694216818114, 5602.075657516464, 5449.209864569694, 5475.780968761364, 5512.503874651301, 5529.806456773994, 5573.414791446839, 5561.582881283839, 5694.0, 5698.173764803997, 5710.885782284414, 5745.597808153389, 3955.7452252215962, 3969.5435024971343, 4146.730462519927, 4146.792660366909, 4175.64507016907, 5539.362973257966, 5659.405762395054, 5666.716664166693, 5725.8568535786935, 5736.913946290725, 5456.972715136399, 5458.0, 5473.151661492475, 5492.5605951524585, 5492.697901615449, 5478.479544443271, 5482.91775623358, 5491.890162196653, 5501.697406642513, 5535.806246781343, 5242.606804489755, 5471.6169868108855, 5489.404440091746, 5498.780048798163, 5514.228008078792, 5510.0, 5535.39110697831, 5535.806246781343, 5544.3346798384455, 5556.499915007668, 5331.280277162688, 5396.21754845553, 5407.053765591085, 5414.242006119225, 5422.620416570802], "value": 4254.404559894654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.3567596191315, 750.3247077630135, 787.2206515549608, 836.4186890111373, 858.4377232912441, 15739.60650983726, 16535.338586456557, 16536.007839529637, 16685.41601043962, 16712.74930876036, 15025.248737563134, 15056.096634201927, 15083.396664133448, 15137.24313784312, 15183.848161518381, 785.7996210966196, 793.1949071692237, 805.1626308639038, 808.0424696734389, 809.951402915824, 802.0295442514564, 806.3267171911461, 806.8103995561039, 808.1918081918081, 809.0130041349544, 850.6682393866397, 853.6457370191363, 853.7353420439683, 854.7435769269225, 854.9786255343604, 847.2078606502939, 849.8512760266959, 850.1073872433919, 850.2857599616322, 850.5789634131082, 619.4115590189339, 623.3174673732256, 623.688155922039, 623.8970569855968, 623.9594426362272, 14777.556673299818, 14795.630109247248, 14955.102693838404, 15859.920700396424, 16567.337306507725, 15521.767173492406, 15766.132862692548, 15766.527004189893, 16013.439529616408, 16270.674586508203, 11373.76256066125, 11509.309441433541, 11822.054235661126, 11954.125707476931, 12092.637220883387, 3750.13746838226, 3915.138669492707, 3920.9803950980063, 4001.979990100031, 4014.8394064237395, 74006.25993740048, 76591.61704191443, 78095.0, 78915.6054219731, 79271.0, 11246.7188320291, 11387.20289579738, 11395.316281023164, 11493.597724079616, 11768.35274059935, 22609.321720348416, 22709.318720438412, 22738.20416285422, 22794.772052279266, 22795.31614051581, 7779.57212353326, 7792.142864284924, 7824.647890844925, 7874.448788584744, 7959.761207163794, 4044.0, 4122.896927576775, 4133.132042271157, 4156.085661154541, 4208.747475151439, 3381.9154521136675, 3432.7768695034742, 3436.4845273209216, 3437.106352348407, 3453.9481907771406, 581.6655423131716, 582.6773224779727, 585.2713371852071, 591.7130191856946, 593.7328202308892, 709.5813470052693, 735.569691730333, 743.8470370925045, 745.5265906149525, 746.2574738135481, 9405.670801521914, 9548.18840398574, 9630.4703241321, 9720.27097967641, 10339.483025848678, 726.6738202779842, 754.5246494708299, 765.4182821056038, 774.9263819937097, 777.564563844249, 4590.1737687215955, 4922.680025798311, 4930.186519224288, 4949.208126699711, 4967.950320496821, 71718.22199116186, 73133.0, 73312.0, 74117.0, 75703.0, 55118.17322740162, 59261.40738592645, 61927.6903615479, 62286.0, 62929.37070629235, 56150.0, 56660.43339566552, 56853.0, 57221.71389143028, 60507.0, 10912.508937098008, 11085.760969194584, 11110.166737494881, 11203.831942521032, 11448.599299024494, 37647.24705505929, 37741.4338784913, 37856.24287514179, 40347.394789078775, 40640.0, 45993.0, 47727.28409073797, 49437.25844112269, 51190.0, 51452.0, 13919.652008699864, 13932.0, 14046.719065618834, 14119.505817296345, 14327.78508322355, 13949.58151255464, 13963.629106693104, 14019.29903504841, 14032.158070515801, 14305.21321327337, 13077.934610326887, 13108.541201057917, 13177.802332964822, 13359.732805343645, 13421.597352079454, 12902.483900643869, 12953.417096230503, 13399.12905661129, 13493.527726529524, 14034.578962631138, 12981.935090324489, 13252.0, 13590.524331648345, 14046.227457489527, 14058.78911816344, 10518.631847885288, 10918.727031824268, 10944.39805810688, 11287.830682539932, 11707.239029463057, 9374.531273436438, 10973.0, 11153.163512736415, 11476.254043487146, 11485.712857178638], "value": 7193.687809471297, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 126.0, 120.0, 155.0, 123.0, 127.0, 120.0, 123.0, 148.0, 120.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 107.0, 77.0, 78.0, 78.0, 78.0, 97.0, 77.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 111.0, 83.0, 83.0, 84.0, 102.0, 82.0, 84.0, 112.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 148.0, 148.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 99.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 56.0, 57.0, 56.0, 56.0, 55.0, 55.0, 58.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 67.0, 62.0, 66.0, 62.0, 62.0, 62.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 85.0, 84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 133.0, 131.0, 135.0, 133.0, 132.0, 133.0, 131.0, 169.0, 162.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 178.0, 178.0, 172.0, 178.0, 176.0, 179.0, 178.0, 171.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 68.0, 79.0, 63.0, 64.0, 64.0, 64.0], "value": 66.1, "unit": "score"}]}]} 20:24:54 INFO - Return code: 0 20:24:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:24:54 ERROR - # TBPL SUCCESS # 20:24:54 INFO - Running post-action listener: _resource_record_post_action 20:24:54 INFO - [mozharness: 2017-02-16 04:24:54.373757Z] Finished run-tests step (success) 20:24:54 INFO - Running post-run listener: _resource_record_post_run 20:24:54 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 405721088; Read time: 8; Write time: 248456 20:24:54 INFO - TinderboxPrint: CPU usage
13.1% 20:24:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 20:24:54 INFO - TinderboxPrint: I/O write bytes / time
405,721,088 / 248,456 20:24:54 INFO - TinderboxPrint: CPU idle
8,286.9 (86.9%) 20:24:54 INFO - TinderboxPrint: CPU user
1,205.6 (12.6%) 20:24:54 INFO - TinderboxPrint: Swap in / out
0 / 0 20:24:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109084672; Read time: 0; Write time: 64760 20:24:54 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295555072; Read time: 8; Write time: 183644 20:24:55 INFO - Running post-run listener: _upload_blobber_files 20:24:55 INFO - Blob upload gear active. 20:24:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:24:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:24:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:24:55 INFO - (blobuploader) - INFO - Open directory for files ... 20:24:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:24:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:24:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:24:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:24:56 INFO - (blobuploader) - INFO - Done attempting. 20:24:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:24:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:24:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:24:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:24:56 INFO - (blobuploader) - INFO - Done attempting. 20:24:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:24:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:24:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:24:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:24:57 INFO - (blobuploader) - INFO - Done attempting. 20:24:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:24:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:25:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:00 INFO - (blobuploader) - INFO - Done attempting. 20:25:00 INFO - (blobuploader) - INFO - Iteration through files over. 20:25:00 INFO - Return code: 0 20:25:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:25:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:25:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bcc3246c87ae3c27e21dc3d53c29b4f13a3f204890601b7751084498a7e93c5ae43d180a2e4ec3697131b321d74b3f2b4e44547c3c4424a73a4082f43d64cc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddba0de1556d712f711662e136cadf57fbc6a2192f7c6cd4f08d11d5cd02557ea7c828d08ebae7ec5d3c7087856a4440b1fb9ca1fb35855524b049745d0c1a1b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c89cbab9e415881161b3bdc389c011a4a2f3858629a79fe89d7f6d2f5e3dc912875750632700f1e6639b14e85f059036d6b6dcc12ce6070f04f2fd07e639dfc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf610bea1ae3239ae8d5dd02fdf1ee9ea617168a0ecde5ea2e02f435cec76a82373b1221c267c3370939d721602239f904c1131b9333628a4263a298e65ba2a"} 20:25:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:25:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:25:00 INFO - Contents: 20:25:00 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bcc3246c87ae3c27e21dc3d53c29b4f13a3f204890601b7751084498a7e93c5ae43d180a2e4ec3697131b321d74b3f2b4e44547c3c4424a73a4082f43d64cc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddba0de1556d712f711662e136cadf57fbc6a2192f7c6cd4f08d11d5cd02557ea7c828d08ebae7ec5d3c7087856a4440b1fb9ca1fb35855524b049745d0c1a1b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c89cbab9e415881161b3bdc389c011a4a2f3858629a79fe89d7f6d2f5e3dc912875750632700f1e6639b14e85f059036d6b6dcc12ce6070f04f2fd07e639dfc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf610bea1ae3239ae8d5dd02fdf1ee9ea617168a0ecde5ea2e02f435cec76a82373b1221c267c3370939d721602239f904c1131b9333628a4263a298e65ba2a"} 20:25:00 INFO - Running post-run listener: copy_logs_to_upload_dir 20:25:00 INFO - Copying logs to upload dir... 20:25:00 INFO - mkdir: /builds/slave/test/build/upload/logs 20:25:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.912957 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-02-15 20:25:00.834530) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 20:25:00.840595) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bcc3246c87ae3c27e21dc3d53c29b4f13a3f204890601b7751084498a7e93c5ae43d180a2e4ec3697131b321d74b3f2b4e44547c3c4424a73a4082f43d64cc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddba0de1556d712f711662e136cadf57fbc6a2192f7c6cd4f08d11d5cd02557ea7c828d08ebae7ec5d3c7087856a4440b1fb9ca1fb35855524b049745d0c1a1b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c89cbab9e415881161b3bdc389c011a4a2f3858629a79fe89d7f6d2f5e3dc912875750632700f1e6639b14e85f059036d6b6dcc12ce6070f04f2fd07e639dfc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf610bea1ae3239ae8d5dd02fdf1ee9ea617168a0ecde5ea2e02f435cec76a82373b1221c267c3370939d721602239f904c1131b9333628a4263a298e65ba2a"} build_url:https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/DQlWbmmJQkmazp498XDVtg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bcc3246c87ae3c27e21dc3d53c29b4f13a3f204890601b7751084498a7e93c5ae43d180a2e4ec3697131b321d74b3f2b4e44547c3c4424a73a4082f43d64cc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddba0de1556d712f711662e136cadf57fbc6a2192f7c6cd4f08d11d5cd02557ea7c828d08ebae7ec5d3c7087856a4440b1fb9ca1fb35855524b049745d0c1a1b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c89cbab9e415881161b3bdc389c011a4a2f3858629a79fe89d7f6d2f5e3dc912875750632700f1e6639b14e85f059036d6b6dcc12ce6070f04f2fd07e639dfc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf610bea1ae3239ae8d5dd02fdf1ee9ea617168a0ecde5ea2e02f435cec76a82373b1221c267c3370939d721602239f904c1131b9333628a4263a298e65ba2a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 20:25:00.865706) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 20:25:00.866025) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1487217841.405150-783211668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 20:25:00.951927) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 20:25:00.952243) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 20:25:00.952540) ========= ========= Total master_lag: 0.24 =========