builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-002 starttime: 1472737945.94 results: success (0) buildid: 20160830093921 builduid: 86ba142624df48b3b49a4204efa8ff7c revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.939477) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.939837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.940103) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.106805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.107101) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-09-01 06:52:26 (14.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.214567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.217962) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020473 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.305052) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.305355) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --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=a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:26,454 truncating revision to first 12 chars 2016-09-01 06:52:26,454 Setting DEBUG logging. 2016-09-01 06:52:26,454 attempt 1/10 2016-09-01 06:52:26,454 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c819e284a139?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:26,749 unpacking tar archive at: mozilla-aurora-c819e284a139/testing/mozharness/ program finished with exit code 0 elapsedTime=0.634374 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.986119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.986404) ========= script_repo_revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.986787) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.987096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.997851) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 5 secs) (at 2016-09-01 06:52:26.998187) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:27 INFO - MultiFileLogger online at 20160901 06:52:27 in /builds/slave/test 06:52:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:52:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:27 INFO - {'append_to_log': False, 06:52:27 INFO - 'base_work_dir': '/builds/slave/test', 06:52:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:27 INFO - 'branch': 'Mozilla-Aurora', 06:52:27 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:27 INFO - 'config_files': ('talos/linux_config.py',), 06:52:27 INFO - 'default_actions': ('clobber', 06:52:27 INFO - 'read-buildbot-config', 06:52:27 INFO - 'download-and-extract', 06:52:27 INFO - 'populate-webroot', 06:52:27 INFO - 'create-virtualenv', 06:52:27 INFO - 'install', 06:52:27 INFO - 'run-tests'), 06:52:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:27 INFO - 'download_minidump_stackwalk': True, 06:52:27 INFO - 'download_symbols': 'ondemand', 06:52:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:27 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:27 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:27 INFO - 'installer_path': 'installer.exe', 06:52:27 INFO - 'log_level': 'info', 06:52:27 INFO - 'log_name': 'talos', 06:52:27 INFO - 'log_to_console': True, 06:52:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:27 INFO - 'opt_config_files': (), 06:52:27 INFO - 'pip_index': False, 06:52:27 INFO - 'sps_profile': False, 06:52:27 INFO - 'sps_profile_interval': 0, 06:52:27 INFO - 'suite': 'dromaeojs-e10s', 06:52:27 INFO - 'system_bits': '32', 06:52:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:27 INFO - 'title': 'talos-linux64-ix-002', 06:52:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:27 INFO - 'use_talos_json': True, 06:52:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:27 INFO - 'work_dir': 'build'} 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.201611Z] Running clobber step. 06:52:27 INFO - Running pre-action listener: _resource_record_pre_action 06:52:27 INFO - Running main action method: clobber 06:52:27 INFO - rmtree: /builds/slave/test/build 06:52:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:28 INFO - Running post-action listener: _resource_record_post_action 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.164591Z] Finished clobber step (success) 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.164691Z] Running read-buildbot-config step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: read_buildbot_config 06:52:28 INFO - Using buildbot properties: 06:52:28 INFO - { 06:52:28 INFO - "project": "", 06:52:28 INFO - "product": "firefox", 06:52:28 INFO - "script_repo_revision": "production", 06:52:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 06:52:28 INFO - "repository": "", 06:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 06:52:28 INFO - "buildid": "20160830093921", 06:52:28 INFO - "pgo_build": "True", 06:52:28 INFO - "basedir": "/builds/slave/test", 06:52:28 INFO - "buildnumber": 522, 06:52:28 INFO - "slavename": "talos-linux64-ix-002", 06:52:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:28 INFO - "platform": "ubuntu64_hw", 06:52:28 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:28 INFO - "branch": "mozilla-aurora", 06:52:28 INFO - "repo_path": "releases/mozilla-aurora", 06:52:28 INFO - "revision": "c819e284a13937fc955c2efb6da7fdf9c5bb8b88", 06:52:28 INFO - "stage_platform": "linux64-pgo", 06:52:28 INFO - "builduid": "86ba142624df48b3b49a4204efa8ff7c", 06:52:28 INFO - "slavebuilddir": "test" 06:52:28 INFO - } 06:52:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:28 INFO - Running post-action listener: _resource_record_post_action 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.167125Z] Finished read-buildbot-config step (success) 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.167215Z] Running download-and-extract step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: download_and_extract 06:52:28 INFO - mkdir: /builds/slave/test/build/tests 06:52:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:28 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:52:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:28 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 06:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:58 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:53:58 INFO - Downloaded 1361 bytes. 06:53:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Using the following test package requirements: 06:53:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'jsshell-linux-x86_64.zip'], 06:53:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:58 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:54:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:28 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:55:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:29 INFO - Downloaded 21769265 bytes. 06:55:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:55:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:55:30 INFO - Return code: 0 06:55:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:55:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:55:30 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:30 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:56:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:01 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:57:02 INFO - Downloaded 21769265 bytes. 06:57:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:57:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:57:03 INFO - Return code: 0 06:57:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:03 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:57:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:03 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 06:57:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:33 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 06:58:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:58:34 INFO - Downloaded 14413723 bytes. 06:58:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:58:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:58:34 INFO - Return code: 0 06:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:58:34 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 06:59:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:04 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:00:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:00:06 INFO - Downloaded 63875997 bytes. 07:00:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - mkdir: /builds/slave/test/properties 07:00:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:06 INFO - Contents: 07:00:06 INFO - build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - Running post-action listener: _resource_record_post_action 07:00:06 INFO - Running post-action listener: set_extra_try_arguments 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.675152Z] Finished download-and-extract step (success) 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.675384Z] Running populate-webroot step. 07:00:06 INFO - Running pre-action listener: _resource_record_pre_action 07:00:06 INFO - Running main action method: populate_webroot 07:00:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:06 INFO - '--tppagecycles', 07:00:06 INFO - '7'], 07:00:06 INFO - 'tests': ['tsvgm']}}, 07:00:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['damp', 'tps']}, 07:00:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['damp', 'tps']}, 07:00:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['dromaeo_dom']}, 07:00:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['basic_compositor_video']}, 07:00:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tsvgx', 07:00:06 INFO - 'tsvgr_opacity', 07:00:06 INFO - 'tart', 07:00:06 INFO - 'tscrollx', 07:00:06 INFO - 'cart']}, 07:00:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:06 INFO - 'tsvgr_opacity', 07:00:06 INFO - 'tart', 07:00:06 INFO - 'tscrollx', 07:00:06 INFO - 'cart']}, 07:00:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tp5o']}, 07:00:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['tp5o']}, 07:00:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s', 07:00:06 INFO - '--xperf_path', 07:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:06 INFO - 'tests': ['tp5n']}, 07:00:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--xperf_path', 07:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:06 INFO - 'tests': ['tp5n']}}, 07:00:06 INFO - 'talos.zip': {'path': '', 07:00:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:06 INFO - Running post-action listener: _resource_record_post_action 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.691694Z] Finished populate-webroot step (success) 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.691784Z] Running create-virtualenv step. 07:00:06 INFO - Running pre-action listener: _resource_record_pre_action 07:00:06 INFO - Running main action method: create_virtualenv 07:00:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:06 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 07:00:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:06 INFO - Using real prefix '/usr' 07:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:09 INFO - Installing pip.................done. 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:09 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 07:00:09 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 07:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 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 07:00:09 INFO - Cleaning up... 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:09 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 07:00:09 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 07:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 INFO - Downloading/unpacking psutil>=3.1.1 07:00:09 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 07:00:09 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 07:00:09 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 07:00:09 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 07:00:09 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 07:00:09 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 07:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:11 INFO - Installing collected packages: psutil 07:00:11 INFO - Running setup.py install for psutil 07:00:11 INFO - building 'psutil._psutil_linux' extension 07:00:11 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 07:00:11 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 07:00:11 INFO - building 'psutil._psutil_posix' extension 07:00:11 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 07:00:12 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 07:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:12 INFO - Successfully installed psutil 07:00:12 INFO - Cleaning up... 07:00:12 INFO - Return code: 0 07:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:12 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:12 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 07:00:12 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 07:00:12 INFO - Using env: {'DISPLAY': ':0', 07:00:12 INFO - 'HOME': '/home/cltbld', 07:00:12 INFO - 'LANG': 'en_US.UTF-8', 07:00:12 INFO - 'LANGUAGE': 'en_US:en', 07:00:12 INFO - 'LOGNAME': 'cltbld', 07:00:12 INFO - 'MAIL': '/var/mail/cltbld', 07:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:12 INFO - 'MOZ_NO_REMOTE': '1', 07:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:12 INFO - 'NO_EM_RESTART': '1', 07:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:12 INFO - 'PWD': '/builds/slave/test', 07:00:12 INFO - 'SHELL': '/bin/bash', 07:00:12 INFO - 'SHLVL': '1', 07:00:12 INFO - 'TERM': 'linux', 07:00:12 INFO - 'TMOUT': '86400', 07:00:12 INFO - 'USER': 'cltbld', 07:00:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:12 INFO - '_': '/tools/buildbot/bin/python'} 07:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:12 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 07:00:12 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 07:00:12 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 07:00:12 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 07:00:12 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 07:00:12 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 07:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:14 INFO - Installing collected packages: mozsystemmonitor 07:00:14 INFO - Running setup.py install for mozsystemmonitor 07:00:14 INFO - Successfully installed mozsystemmonitor 07:00:14 INFO - Cleaning up... 07:00:14 INFO - Return code: 0 07:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:14 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:14 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 07:00:14 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 07:00:14 INFO - Using env: {'DISPLAY': ':0', 07:00:14 INFO - 'HOME': '/home/cltbld', 07:00:14 INFO - 'LANG': 'en_US.UTF-8', 07:00:14 INFO - 'LANGUAGE': 'en_US:en', 07:00:14 INFO - 'LOGNAME': 'cltbld', 07:00:14 INFO - 'MAIL': '/var/mail/cltbld', 07:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:14 INFO - 'MOZ_NO_REMOTE': '1', 07:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:14 INFO - 'NO_EM_RESTART': '1', 07:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:14 INFO - 'PWD': '/builds/slave/test', 07:00:14 INFO - 'SHELL': '/bin/bash', 07:00:14 INFO - 'SHLVL': '1', 07:00:14 INFO - 'TERM': 'linux', 07:00:14 INFO - 'TMOUT': '86400', 07:00:14 INFO - 'USER': 'cltbld', 07:00:14 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:14 INFO - '_': '/tools/buildbot/bin/python'} 07:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:14 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 07:00:14 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 07:00:14 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 07:00:14 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 07:00:14 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 07:00:14 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 07:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:16 INFO - warning: no files found matching '*.txt' 07:00:16 INFO - no previously-included directories found matching 'build' 07:00:16 INFO - no previously-included directories found matching 'dist' 07:00:16 INFO - no previously-included directories found matching '.git*' 07:00:16 INFO - Installing collected packages: jsonschema, functools32 07:00:16 INFO - Running setup.py install for functools32 07:00:17 INFO - warning: no files found matching '*.txt' 07:00:17 INFO - no previously-included directories found matching 'build' 07:00:17 INFO - no previously-included directories found matching 'dist' 07:00:17 INFO - no previously-included directories found matching '.git*' 07:00:17 INFO - Successfully installed jsonschema functools32 07:00:17 INFO - Cleaning up... 07:00:17 INFO - Return code: 0 07:00:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:17 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 07:00:17 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 07:00:17 INFO - Using env: {'DISPLAY': ':0', 07:00:17 INFO - 'HOME': '/home/cltbld', 07:00:17 INFO - 'LANG': 'en_US.UTF-8', 07:00:17 INFO - 'LANGUAGE': 'en_US:en', 07:00:17 INFO - 'LOGNAME': 'cltbld', 07:00:17 INFO - 'MAIL': '/var/mail/cltbld', 07:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:17 INFO - 'MOZ_NO_REMOTE': '1', 07:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:17 INFO - 'NO_EM_RESTART': '1', 07:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:17 INFO - 'PWD': '/builds/slave/test', 07:00:17 INFO - 'SHELL': '/bin/bash', 07:00:17 INFO - 'SHLVL': '1', 07:00:17 INFO - 'TERM': 'linux', 07:00:17 INFO - 'TMOUT': '86400', 07:00:17 INFO - 'USER': 'cltbld', 07:00:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:17 INFO - '_': '/tools/buildbot/bin/python'} 07:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:17 INFO - Cleaning up... 07:00:17 INFO - Return code: 0 07:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:17 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 07:00:17 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 07:00:17 INFO - Using env: {'DISPLAY': ':0', 07:00:17 INFO - 'HOME': '/home/cltbld', 07:00:17 INFO - 'LANG': 'en_US.UTF-8', 07:00:17 INFO - 'LANGUAGE': 'en_US:en', 07:00:17 INFO - 'LOGNAME': 'cltbld', 07:00:17 INFO - 'MAIL': '/var/mail/cltbld', 07:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:17 INFO - 'MOZ_NO_REMOTE': '1', 07:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:17 INFO - 'NO_EM_RESTART': '1', 07:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:17 INFO - 'PWD': '/builds/slave/test', 07:00:17 INFO - 'SHELL': '/bin/bash', 07:00:17 INFO - 'SHLVL': '1', 07:00:17 INFO - 'TERM': 'linux', 07:00:17 INFO - 'TMOUT': '86400', 07:00:17 INFO - 'USER': 'cltbld', 07:00:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:17 INFO - '_': '/tools/buildbot/bin/python'} 07:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 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 07:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:20 INFO - Downloading docopt-0.6.1.tar.gz 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:20 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:20 INFO - Running setup.py install for blobuploader 07:00:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:20 INFO - Running setup.py install for requests 07:00:20 INFO - Running setup.py install for docopt 07:00:20 INFO - Successfully installed blobuploader requests docopt 07:00:20 INFO - Cleaning up... 07:00:20 INFO - Return code: 0 07:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:20 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:20 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 07:00:20 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 07:00:20 INFO - Using env: {'DISPLAY': ':0', 07:00:20 INFO - 'HOME': '/home/cltbld', 07:00:20 INFO - 'LANG': 'en_US.UTF-8', 07:00:20 INFO - 'LANGUAGE': 'en_US:en', 07:00:20 INFO - 'LOGNAME': 'cltbld', 07:00:20 INFO - 'MAIL': '/var/mail/cltbld', 07:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:20 INFO - 'MOZ_NO_REMOTE': '1', 07:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:20 INFO - 'NO_EM_RESTART': '1', 07:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:20 INFO - 'PWD': '/builds/slave/test', 07:00:20 INFO - 'SHELL': '/bin/bash', 07:00:20 INFO - 'SHLVL': '1', 07:00:20 INFO - 'TERM': 'linux', 07:00:20 INFO - 'TMOUT': '86400', 07:00:20 INFO - 'USER': 'cltbld', 07:00:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:20 INFO - '_': '/tools/buildbot/bin/python'} 07:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:21 INFO - Running setup.py (path:/tmp/pip-7zbSbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:21 INFO - Running setup.py (path:/tmp/pip-csZVX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:21 INFO - Running setup.py (path:/tmp/pip-xbAiQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:21 INFO - Running setup.py (path:/tmp/pip-N5Yllu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:21 INFO - Running setup.py (path:/tmp/pip-7bVknw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:21 INFO - Running setup.py (path:/tmp/pip-XEVUSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:21 INFO - Running setup.py (path:/tmp/pip-YZ0rNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:21 INFO - Running setup.py (path:/tmp/pip-xxNeFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:21 INFO - Running setup.py (path:/tmp/pip-uCXb_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:21 INFO - Running setup.py (path:/tmp/pip-OABKe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:21 INFO - Running setup.py (path:/tmp/pip-041jOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:21 INFO - Running setup.py (path:/tmp/pip-OoiEFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:21 INFO - Running setup.py (path:/tmp/pip-W6m4jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:21 INFO - Running setup.py (path:/tmp/pip-oPa_2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:22 INFO - Running setup.py (path:/tmp/pip-IzPJ4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:22 INFO - Running setup.py (path:/tmp/pip-bheSyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:22 INFO - Running setup.py (path:/tmp/pip-riMQ_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:22 INFO - Running setup.py install for manifestparser 07:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozcrash 07:00:22 INFO - Running setup.py install for mozdebug 07:00:22 INFO - Running setup.py install for mozdevice 07:00:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozfile 07:00:22 INFO - Running setup.py install for mozhttpd 07:00:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozinfo 07:00:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozInstall 07:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozleak 07:00:23 INFO - Running setup.py install for mozlog 07:00:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for moznetwork 07:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozprocess 07:00:23 INFO - Running setup.py install for mozprofile 07:00:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozrunner 07:00:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozscreenshot 07:00:23 INFO - Running setup.py install for moztest 07:00:23 INFO - Running setup.py install for mozversion 07:00:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:24 INFO - Cleaning up... 07:00:24 INFO - Return code: 0 07:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:24 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:24 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 07:00:24 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 07:00:24 INFO - Using env: {'DISPLAY': ':0', 07:00:24 INFO - 'HOME': '/home/cltbld', 07:00:24 INFO - 'LANG': 'en_US.UTF-8', 07:00:24 INFO - 'LANGUAGE': 'en_US:en', 07:00:24 INFO - 'LOGNAME': 'cltbld', 07:00:24 INFO - 'MAIL': '/var/mail/cltbld', 07:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:24 INFO - 'MOZ_NO_REMOTE': '1', 07:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:24 INFO - 'NO_EM_RESTART': '1', 07:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:24 INFO - 'PWD': '/builds/slave/test', 07:00:24 INFO - 'SHELL': '/bin/bash', 07:00:24 INFO - 'SHLVL': '1', 07:00:24 INFO - 'TERM': 'linux', 07:00:24 INFO - 'TMOUT': '86400', 07:00:24 INFO - 'USER': 'cltbld', 07:00:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:24 INFO - '_': '/tools/buildbot/bin/python'} 07:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:24 INFO - Running setup.py (path:/tmp/pip-Jvc3Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:24 INFO - Running setup.py (path:/tmp/pip-28NBDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:24 INFO - Running setup.py (path:/tmp/pip-heu6t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:24 INFO - Running setup.py (path:/tmp/pip-zWZxYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:24 INFO - Running setup.py (path:/tmp/pip-MDh32o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:24 INFO - Running setup.py (path:/tmp/pip-QDRVPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:24 INFO - Running setup.py (path:/tmp/pip-nhrFHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:24 INFO - Running setup.py (path:/tmp/pip-6FAgAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:24 INFO - Running setup.py (path:/tmp/pip-YBZW4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:24 INFO - Running setup.py (path:/tmp/pip-iz2XHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:24 INFO - Running setup.py (path:/tmp/pip-5sE7tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:25 INFO - Running setup.py (path:/tmp/pip-uUf_k9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:25 INFO - Running setup.py (path:/tmp/pip-pmiQ0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:25 INFO - Running setup.py (path:/tmp/pip-sgJbR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:25 INFO - Running setup.py (path:/tmp/pip-pZoS7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:25 INFO - Running setup.py (path:/tmp/pip-8YX6e5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:25 INFO - Running setup.py (path:/tmp/pip-HIbX94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:25 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)) 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:00:25 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)) 07:00:25 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:00:25 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:25 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 07:00:25 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 07:00:25 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 07:00:25 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 07:00:25 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 07:00:25 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 07:00:27 INFO - Downloading blessings-1.6.tar.gz 07:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:27 INFO - Installing collected packages: blessings 07:00:27 INFO - Running setup.py install for blessings 07:00:27 INFO - Successfully installed blessings 07:00:27 INFO - Cleaning up... 07:00:27 INFO - Return code: 0 07:00:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:27 INFO - Reading from file tmpfile_stdout 07:00:27 INFO - Current package versions: 07:00:27 INFO - argparse == 1.2.1 07:00:27 INFO - blessings == 1.6 07:00:27 INFO - blobuploader == 1.2.4 07:00:27 INFO - docopt == 0.6.1 07:00:27 INFO - functools32 == 3.2.3-2 07:00:27 INFO - jsonschema == 2.5.1 07:00:27 INFO - manifestparser == 1.1 07:00:27 INFO - mozInstall == 1.12 07:00:27 INFO - mozcrash == 0.17 07:00:27 INFO - mozdebug == 0.1 07:00:27 INFO - mozdevice == 0.48 07:00:27 INFO - mozfile == 1.2 07:00:27 INFO - mozhttpd == 0.7 07:00:27 INFO - mozinfo == 0.9 07:00:27 INFO - mozleak == 0.1 07:00:27 INFO - mozlog == 3.3 07:00:27 INFO - moznetwork == 0.27 07:00:27 INFO - mozprocess == 0.23 07:00:27 INFO - mozprofile == 0.28 07:00:27 INFO - mozrunner == 6.12 07:00:27 INFO - mozscreenshot == 0.1 07:00:27 INFO - mozsystemmonitor == 0.3 07:00:27 INFO - moztest == 0.7 07:00:27 INFO - mozversion == 1.4 07:00:27 INFO - psutil == 3.1.1 07:00:27 INFO - requests == 1.2.3 07:00:27 INFO - wsgiref == 0.1.2 07:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:27 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:27 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 07:00:27 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 07:00:27 INFO - Using env: {'DISPLAY': ':0', 07:00:27 INFO - 'HOME': '/home/cltbld', 07:00:27 INFO - 'LANG': 'en_US.UTF-8', 07:00:27 INFO - 'LANGUAGE': 'en_US:en', 07:00:27 INFO - 'LOGNAME': 'cltbld', 07:00:27 INFO - 'MAIL': '/var/mail/cltbld', 07:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:27 INFO - 'MOZ_NO_REMOTE': '1', 07:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:27 INFO - 'NO_EM_RESTART': '1', 07:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:27 INFO - 'PWD': '/builds/slave/test', 07:00:27 INFO - 'SHELL': '/bin/bash', 07:00:27 INFO - 'SHLVL': '1', 07:00:27 INFO - 'TERM': 'linux', 07:00:27 INFO - 'TMOUT': '86400', 07:00:27 INFO - 'USER': 'cltbld', 07:00:27 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:27 INFO - '_': '/tools/buildbot/bin/python'} 07:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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 0x1fc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ef400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', '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 07:00:28 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 07:00:28 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 07:00:28 INFO - Using env: {'DISPLAY': ':0', 07:00:28 INFO - 'HOME': '/home/cltbld', 07:00:28 INFO - 'LANG': 'en_US.UTF-8', 07:00:28 INFO - 'LANGUAGE': 'en_US:en', 07:00:28 INFO - 'LOGNAME': 'cltbld', 07:00:28 INFO - 'MAIL': '/var/mail/cltbld', 07:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:28 INFO - 'MOZ_NO_REMOTE': '1', 07:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:28 INFO - 'NO_EM_RESTART': '1', 07:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:28 INFO - 'PWD': '/builds/slave/test', 07:00:28 INFO - 'SHELL': '/bin/bash', 07:00:28 INFO - 'SHLVL': '1', 07:00:28 INFO - 'TERM': 'linux', 07:00:28 INFO - 'TMOUT': '86400', 07:00:28 INFO - 'USER': 'cltbld', 07:00:28 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:28 INFO - '_': '/tools/buildbot/bin/python'} 07:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Running post-action listener: _resource_record_post_action 07:00:28 INFO - Running post-action listener: _start_resource_monitoring 07:00:28 INFO - Starting resource monitoring. 07:00:28 INFO - [mozharness: 2016-09-01 14:00:28.275712Z] Finished create-virtualenv step (success) 07:00:28 INFO - [mozharness: 2016-09-01 14:00:28.276219Z] Running install step. 07:00:28 INFO - Running pre-action listener: _resource_record_pre_action 07:00:28 INFO - Running main action method: install 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:28 INFO - Reading from file tmpfile_stdout 07:00:28 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:28 INFO - Reading from file tmpfile_stdout 07:00:28 INFO - Output received: 07:00:28 INFO - Usage: mozinstall [options] installer 07:00:28 INFO - Options: 07:00:28 INFO - -h, --help show this help message and exit 07:00:28 INFO - -d DEST, --destination=DEST 07:00:28 INFO - Directory to install application into. [default: 07:00:28 INFO - "/builds/slave/test"] 07:00:28 INFO - --app=APP Application being installed. [default: firefox] 07:00:28 INFO - mkdir: /builds/slave/test/build/application 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:00:45 INFO - Reading from file tmpfile_stdout 07:00:45 INFO - Output received: 07:00:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.296462Z] Finished install step (success) 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.296740Z] Running run-tests step. 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: run_tests 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:45 INFO - Python 2.7.3 07:00:45 INFO - Return code: 0 07:00:45 INFO - grabbing minidump binary from tooltool 07:00:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:45 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 0x227e570>, '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 0x227f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227f900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:45 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 07:00:45 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 07:00:45 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 07:00:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:45 INFO - Return code: 0 07:00:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:00:45 INFO - Using env: {'DISPLAY': ':0', 07:00:45 INFO - 'HOME': '/home/cltbld', 07:00:45 INFO - 'LANG': 'en_US.UTF-8', 07:00:45 INFO - 'LANGUAGE': 'en_US:en', 07:00:45 INFO - 'LOGNAME': 'cltbld', 07:00:45 INFO - 'MAIL': '/var/mail/cltbld', 07:00:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:45 INFO - 'MOZ_NO_REMOTE': '1', 07:00:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:45 INFO - 'NO_EM_RESTART': '1', 07:00:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:45 INFO - 'PWD': '/builds/slave/test', 07:00:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:45 INFO - 'SHELL': '/bin/bash', 07:00:45 INFO - 'SHLVL': '1', 07:00:45 INFO - 'TERM': 'linux', 07:00:45 INFO - 'TMOUT': '86400', 07:00:45 INFO - 'USER': 'cltbld', 07:00:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487', 07:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:45 INFO - '_': '/tools/buildbot/bin/python'} 07:00:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:00:45 INFO - mozversion application_buildid: 20160830093921 07:00:45 INFO - mozversion application_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 07:00:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:45 INFO - mozversion application_name: Firefox 07:00:45 INFO - mozversion application_remotingname: firefox-dev 07:00:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:45 INFO - mozversion application_vendor: Mozilla 07:00:45 INFO - mozversion application_version: 50.0a2 07:00:45 INFO - mozversion platform_buildid: 20160830093921 07:00:45 INFO - mozversion platform_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 07:00:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:45 INFO - mozversion platform_version: 50.0a2 07:00:45 INFO - using testdate: 1472738445 07:00:45 INFO - actual date: 1472738445 07:00:45 INFO - starting webserver on 'localhost:51320' 07:00:45 INFO - SUITE-START | Running 2 tests 07:00:45 INFO - TEST-START | dromaeo_css 07:00:45 INFO - operating with platform_type : linux_ 07:00:45 INFO - Initialising browser for dromaeo_css test... 07:00:45 INFO - TEST-INFO | started process 12624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWnfjAC/profile http://localhost:51320/getInfo.html) 07:00:50 INFO - PROCESS | 12624 | __metrics Screen width/height:1600/1200 07:00:50 INFO - PROCESS | 12624 | colorDepth:24 07:00:50 INFO - PROCESS | 12624 | Browser inner width/height: 1024/697 07:00:50 INFO - PROCESS | 12624 | __metrics 07:00:50 ERROR - PROCESS | 12624 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:00:51 INFO - TEST-INFO | 12624: exit 0 07:00:51 INFO - Browser initialized. 07:00:51 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:51 INFO - TEST-INFO | started process 12748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWnfjAC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:03 INFO - PROCESS | 12748 | RSS: Main: 152768512 07:01:03 INFO - PROCESS | 12748 | 07:01:53 INFO - PROCESS | 12748 | 1472738513107 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:04:01 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51320/tests/dromaeo/cssquery-ext.html) 07:04:02 INFO - PROCESS | 12748 | RSS: Main: 157884416 07:04:02 INFO - PROCESS | 12748 | 07:07:04 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-ext.html (next: http://localhost:51320/tests/dromaeo/cssquery-jquery.html) 07:07:05 INFO - PROCESS | 12748 | RSS: Main: 154095616 07:07:05 INFO - PROCESS | 12748 | 07:10:07 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51320/tests/dromaeo/cssquery-mootools.html) 07:10:08 INFO - PROCESS | 12748 | RSS: Main: 154812416 07:10:08 INFO - PROCESS | 12748 | 07:13:09 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51320/tests/dromaeo/cssquery-prototype.html) 07:13:10 INFO - PROCESS | 12748 | RSS: Main: 153632768 07:13:10 INFO - PROCESS | 12748 | 07:16:13 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51320/tests/dromaeo/cssquery-yui.html) 07:16:13 INFO - PROCESS | 12748 | RSS: Main: 155693056 07:16:13 INFO - PROCESS | 12748 | 07:19:15 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-yui.html (next: http://localhost:51320/tests/dromaeo/cssquery-dojo.html) 07:19:16 INFO - PROCESS | 12748 | RSS: Main: 155754496 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | __start_tp_report 07:19:16 INFO - PROCESS | 12748 | _x_x_mozilla_page_load 07:19:16 INFO - PROCESS | 12748 | _x_x_mozilla_page_load_details 07:19:16 INFO - PROCESS | 12748 | |i|pagename|runs| 07:19:16 INFO - PROCESS | 12748 | |0;dojo.html;3342.766006379553;3363.865445382185;3366.562346894904;3388.6272510023878;3393.4740115282125;5832.883342333156;5912.408759124085;5916.437938395857;5954.047352423608;5956.255468066492;5820.243368362107;5881.382454842244;5904.0553511438175;5911.556633252512;5960.6423614583155;5334.893302133955;5397.514223719864;5401.594880383967;5447.210154527591;5450.29146210992;5256.5794736421185;5278.155495120782;5283.943211357725;5295.179247216687;5341.1988201769655;5293.788248470076;5324.281222035014;5342.813001544947;5356.758945847446;5394.05604019297;5373.704446255435;5380.482679984239;5386.542143917772;5386.703731294777;5395.082835917903;5176.7929282828645;5195;5195.792168313263;5289.788408463657;5389.353277606673;5783.8554036149435;5890.087036509348;5904.645721256696;5920.97039514804;5945.227120474354;6329.651869147195;6356.300806911282;6373.681315934185;6383.361663833626;6398.712057957357;6142.590862071548;6277.654728989904;6421.165248517709;6423.389777971085;6424.871502569969;5872.06047032477;5936.673482958478;5941.346451890288;5961.195238642815;5969.044952807573;6475.190601174853;6487.54587178893;6539.6403197824575;6546.541742078104;6547.34526547351;5970.731317090741;6125.723854043132;6280.623162610258;6283.96858015707;6292.937070629327;6222.62664240147;6363.140975968185;6365.96817015912;6392.137061496638;6393.232812062489;6356.713947872357;6368.044793281045;6371.267304259976;6371.776987805497;6373.362663733589;6114.601521188728;6180.289266734383;6368.172137622079;6370.617762934239;6374.426301632782;4759.809607615656;4810.374651295309;4824.131656301899;4834.226523756182;4841.12859685253;4273.615374616299;4274.850380236677;4276.743395396286;4303.07483890965;4304.010077682151;5915.1718759373725;5980.222571065734;6069.969650151809;6107.4197951194565;6139.079138129227;6275.874482510324;6430.517711171681;6445.581037232565;6452.22573291208;6459.418652321314;6075.453209211192;6124.295705993779;6137.662428566382;6138.654433333754;6141.170941922784;5322.042032434199;5399.999999999922;5494.80768173112;5506.587005974568;5533.6403133796175;6433.806985790434;6556.24603170632;6563.048357988065;6573.835654108686;6578.63817490033;6372.330905254881;6382.489400848014;6388.520860935356;6389.7124629390855;6394.328595497405;6289.40250676185;6441.3880681335195;6530.640814755234;6535.085088087577;6595.142631457879;6191.504679625529;6322.178116844781;6326.4622507087415;6335.2714437838395;6336.7148478319505;6422.454091402096;6429.260635027032;6434.873897068088;6440;6441.484681225585;6047.334793172836;6125.204929265209;6285.717142728679;6291.653959032297;6301.558890877778;6115.666295175977;6191.071339299141;6228.532860035606;6239.0953311770445;6254.061890716429;6108.08378743192;6152.723127459365;6152.969235153795;6157.230346206724;6164.198654174928;5985.700714964321;6130.489860652556;6171.722272497838;6195.2255968004;6255.374462553708;6020.217371741645;6127.780302992555;6136.94800169746;6143.109249158758;6150.323464419 07:19:16 INFO - PROCESS | 12748 | |1;ext.html;594.3996563470896;637.7321524959517;652.9085927970085;661.1966460750189;701.6351497221448;42364.57635423645;47282.29076563854;47315.52684473158;47393;47507.28739068917;47202;47740.52259477404;47794.283085753654;47808;47877.28184077242;9234.168924796784;9361.48511831849;9371.29715271354;9385.202257808094;9390.436573805559;9272.304577156707;9300.488473133975;9345;9376.671816486425;9426.245900327956;9454.05459454056;9474.763130921714;9484.573194206243;9520.8571871422;9574.80850382992;9379.296552758537;9404.62381504739;9425.528723563795;9443.338966272364;9446.763830904283;9246.167844893991;9297.023812499725;9308.534573271378;9313.534323283877;9336.299777516713;44719;45223.321650175276;45314.54685453136;45381.09237815225;45410.54589454096;3165.3511030238747;3725.4784330193793;3768.9811550942345;3794.0704527390967;3839.308924393608;33701.32597348064;34258.48612270818;34291.48562771561;34324.6567534324;34392;4479.664025198123;4525.230710779176;4539.4779600346055;4734.905301893943;4735.976320118378;334806;334927;338291;338475;340344;38796.22407551833;39803.20393592112;39887.401688974685;39888;39936.800315998815;39681.404778928336;41216.587834121274;41302.79348603298;41470.170596588505;41651.583484164774;17691.73462398065;17857.642847142983;17881.552961176076;17919.37282195117;17959;13990.160590364407;14003.509877154454;14103.365348559339;14115.22366269865;14217.644558885904;9279.860802087975;9290.442573445614;9325.533723313807;9339.533023348942;9481.525923703924;3558.839852206657;3564.0733409313766;3614.1145419372424;3635.709143268532;3639.1993761372455;1064.079571170947;1096.2655021135859;1106.8727096383857;1156.9652910412701;1166.2618526079523;35115.29769404598;35263.471047934305;35478.645213547534;35532.64467355345;35557.822210889295;1132.6360708695163;1163.3194581169942;1184.1296646964474;1218.4456072486996;1225.2770865189582;17922.096090814593;18340.54148646268;18513.629727405376;18523.259069637334;18838.15228314729;331885;335074;335131;335312;338425;64691;65331.67334163299;65678.67160664262;65757.67121164364;65944.6702766483;1459.3287087939666;1465.560331900447;1466.74331991901;1478.9260536973109;1494.0438119603461;35333.646663533036;36231.818840905624;36250.8187459061;36355.091122722144;36501;58328.708356457944;62552.68723656535;63923.36076639174;64497.355026449135;68966.31033689599;557642;560293;562672;563789;563928;49461.50538494569;49585.75207123941;50049.749751252464;50103.74948125236;50518.49481505138;54579;54884;55016.44983550113;55189.17216241679;55535.44464555303;45656.31515527336;47059.29411058768;47753.761231193625;47851.76074119746;48700.269495958295;47790.52209477861;47959.520404795505;47991.520084800104;48619.75690121527;48642.5135748638;40153.39769903512;44699.32951005803;45497.545024549334;45732.77133614311;46018;39660.20679586334;40640;40769.59230407658;40930.79534602308;41333;31084;31227.53158702576;31563.21091972719;31654.525182122747;31811.20471988219 07:19:16 INFO - PROCESS | 12748 | |2;jquery.html;422.4571425717955;423.1663622663353;425.156065210557;425.2218440254335;457.7551010209539;20393.694094588576;21182.364529064114;21201.363959081234;21587;22220.88889555554;19567.412977610693;20271.59456810863;20579.588408231826;20671.483212919684;20879;5746.770129194838;5964.79123230687;5968.462838344548;6000.579959402844;6008.8497787555225;5450.890982180354;5695.345035320929;5730.885382292352;5891.322497912731;5915.201447804534;5730.7707691692285;5744.310682718059;5765.336986246593;5793.971030144843;5806.767729290823;5737.454941780545;5802.187693722861;6118.816435506919;6252.749890004395;6257.5932564383165;5804.332501762309;5888.735006924699;5911.586188966774;5917.704114794243;6020.969895150496;13199.01007424447;13337.252049854002;13400.865991340059;24596.754032459805;24823.13119040843;2473.295110893405;2554.923352299434;2708.526007948601;3106.6427360853563;3156.2425017995747;10460.424676642782;10690.086080285193;14460;14764.409423623043;15313.923430382776;1939.4472575315967;2024.7975202479633;2034.074496104269;2045.3659365596714;2067.5968186203695;88892;89266;89712.10287897168;90222.09777902202;90268.09731902728;11828.290302581872;11891.405429728478;12126;12211;12260.203086799329;16812.159392030593;16826;16830.242639081273;16885.831141688424;16910.915445422695;8797.912020879709;9049.185573298435;9107.863382049141;9178.67874624385;9209.35534512591;8533.871991920127;8806.559672016374;8912.546321329897;8933.46399216049;9212.078792120734;4833.927491087637;4886.853394398174;5079.288899554065;5176.472940482567;5201.420203442648;2023.7065625484518;2070.523779530686;2162.7945345192497;2166.653335466318;2186.6610675345346;986.2997271936862;1039.6212309992525;1079.4109585627818;1091.7379828841022;1092.2954694222183;20766.169353226003;20929.372118836458;20972.580548388945;21199.78800211978;21341.14635414597;1028.3367228137809;1030.907218350352;1039.5270152080936;1041.2398948767443;1074.6990842564094;11056.889431105586;12305.138640295272;12355.6911077222;12527.37363131858;12552.435140418704;494883;508148;510582;510616;515412;90989.54505227564;91249;93972.53013734888;98565;99702;485.51206037931655;508.22495694066106;529.6424913183586;530.4138926486254;530.6603773584986;11750.764984700429;11771.293722376684;11796.646100616996;12322.507099715911;12377.814332784836;41071.7946410266;42770.78614606907;43632.12735745331;44189.33715994327;45705.771471142434;61240.69379653073;61551.384486154566;62009.68995154995;63045.36954630578;63092.36907631048;8554.443961142506;8783.472991620523;8804.207621314112;8814.118588141135;9112.817743645222;8508;12761.234325940102;13776.862231377558;14059.789103163255;14215.644608884448;12023.338716370683;12308.384580770746;12336.444859981502;12409.503619855466;12520.248785072925;11903.880961190624;12162.574309899112;12179.391030448265;12242.143049986415;12541.31022793755;12430.12989090755;12432.875671243171;12517.311547864954;12559.246445213317;12694.809577856524;8613;8757.60590773404;8861.468311901306;9002.414843035182;9123.452592844451;10178.847317290394;12111.818322725343;12234.204776689487;12491.687707807378;12519.93740031294 07:19:16 INFO - PROCESS | 12748 | |3;mootools.html;1547.8916475846693;1571.065216196363;1748.2482532511024;1863.8974856382893;1886.2172198537598;8644.610992505337;9395.953020234907;9589.18491928186;10119.342242754234;10120.34217775845;9552.856707149398;9923.603055877757;9963.850542241873;9973;9997.050280223386;2525.7095434025136;2550.41340491687;2564.2563656539583;2572.0354866924904;2573.4209802794408;2454.447749256421;2581.070814506776;2581.4966081614093;2586.844789312647;2603.9349016274555;2502.6621406110216;2534;2571.6656834611567;2576.8325058871205;2584.3797488602695;2513.258588716324;2515.333436639292;2517.0561039610147;2552;2558.3731985663444;2469.876506174702;2486.17956074495;2487.3905893056135;2501.312139161727;2518.26970178648;9881.505924703808;9900.702978910644;10028.348157369748;10030.297879148462;10081.193504519693;5479.315085614298;5577.3028371453565;5591.189277554772;5598.720063996784;5600.719964001783;3666.431703086026;3741.5136032315895;3774.5093137892172;3819.9045023874623;3877.3990031545154;1199.3343694249688;1228.2139430764319;1254.5859866244118;1264.2667252993242;1269.0608949377486;14217.85782142186;14256.857431425657;14292.85707142936;14316.498922537661;14336.42654293827;5064.645474816727;5171.7672704728375;5255.842324730264;5257.842264732064;5292;11850.407479625985;11860.169788114921;11939.044876409866;11959.940200298943;12003.459844307026;5911.438413350725;5914.497267732291;5917.437843404869;5925.525957923356;5928.940710592925;4733.313669517901;5172.870678233075;5322.787088516416;5373.435789242073;5435.972820135953;4397.538258482878;4438.622717069085;4479.843205487792;4518;4594.264917613166;1360.3810266328796;1376.3462355381314;1383.1493631416606;1387.8681525255085;1404.1504889541884;1018.5365658625308;1078.719532921446;1079.7516571188514;1083.8590983172137;1091.6070214722693;6570.342965703391;6653.534252602368;6739.663016849138;6766.086578311963;6848.8972665409065;483.1279540429558;485.39083450269686;489.86283840524;491.0792264504055;496.85591178557814;4543.636509079266;4604.1252162089095;4667.556582124693;4792;4844.176489996709;12036.45835937385;12224.938875305566;12344.75310493785;12345.567905123457;12398.75202495945;11480.425978701032;11981.88018119825;12014.699632509257;12440.81338779919;12511.186772859735;9019.77450563728;9143.634254629873;9150.496722680316;9158.404703694372;9251.583678734338;6373.2989371168205;6627.1716035495565;6628.90056649141;6645.368689974444;6668.933310667026;11314.60398886068;11345.886541134483;11359.432028398382;11404.714882128013;11454.427278635869;11249.1563132767;11325.490352934303;11529;11622.302661840316;11676.474558644712;7443.069616297963;8499;8799.780005499913;8836.91163088361;8893.599788009426;9162.816743665222;9388.812223755624;9456.432614042904;9479.146876781122;9506.429614223169;8214.301784348158;8426.873596895928;8448.831023379375;8497.405181637474;8707.521086340312;7984.720534781255;8009.759707208793;8361;8431.988161420424;8463.534505602005;7473.140588832136;7787.5716835574585;7820.648070836713;7842.529448233125;7861.371090312876;5613.793034497603;5695.373508913934;5715.628484148517;5761.222234998221;5794.797182098607;5820.447057529528;5881.441263080001;5948.970255148696;5953.672548009901;6063.939360606338 07:19:16 INFO - PROCESS | 12748 | |4;prototype.html;341.8957218048496;361.6130740108085;362.21760996248094;365.84268764431283;367.95952445231035;5673.574481913863;5772.3361813391475;5779.479846813786;5819.301683797945;5857.326407463148;5757.798477053304;5784.363719990798;5795.884082318362;5804.071348584227;5821.56338274629;4588.17412865684;4591.7244965302025;4606.723596584207;4621.2837010263465;4636.744979026153;4480.6191473724775;4580.274736531758;4584.587387135157;4595.908081838361;4622.560856718623;4524.185646583614;4538.954610453886;4545.772641386823;4559.088182363524;4567.109413664337;4561.156186105564;4562.4753153387455;4582.747948862844;4586.220342541776;4609.608183304423;3884.038560526944;3942.073612701013;3945.3884647879627;3953.743006704555;3972.8013599320207;5611.691356975365;5617.578681598856;5650.943490565124;5653.551333686448;5660.264165658438;5091.210862316347;5153.149730294497;5179.14544100223;5181.766820493049;5193.922091168635;6038.30559485656;6043.335233124397;6048.455638992467;6075.574709770318;6079.233948553442;4351.6518678505645;4353.238183317939;4353.303471444554;4363.716358436692;4370;6095.725692343811;6285.371462853678;6337.397947195009;6339.492840572743;6362.713677884506;4466.129104824593;4466.798994045211;4470.642348612103;4478.57453541913;4486.820527178941;4513.571210734975;4521.186186486464;4553.317002449659;4554.497015984717;4589.954100458953;4956.306117143603;5138.58891288696;5217.765200565984;5221.598064890117;5233.188855727369;5979.402059793986;6065.636061836304;6132.386761323921;6148.538859585549;6180.258368995749;1535.7696345548034;1577.5188567487028;1578.329210085709;1582.114016150959;1584.1683116364002;969.8011907559074;985.669800616787;1000.7940431779833;1006.3646574316735;1025.179856115105;5179.766910489037;5313.483538991826;5315.787368505294;5321.190795129674;5370.610078515023;5521.834344969658;5557.3886872444;5571.498565129158;5577.247071645357;5612.492192379763;5017.365882911501;5281.709505977208;5289.894202115936;5360.892782144335;5381.919271210935;2940.867660955262;2950.7196816302417;2968.6437627484406;3005.879764809427;3006.406604499619;5666.413404246818;5678.290213723285;5708.65748055118;5754.539636829044;5776.353739184312;5984.371640977722;6375.171227740364;6442.259140198843;6451.161349024596;6474.352564743488;6505.447037001908;6521.510886683518;6545.901811472737;6561.015847622894;6574.605523668595;6081.27024757032;6286.8428289291305;6298.464630506275;6306.400891915261;6321.620702757849;6225.782097626561;6239.532035097295;6256.061590761422;6263.248410190807;6272.8118156455375;6361.363863613787;6464.864540697852;6506.934930650633;6524.934750652433;6542.738290468328;4487.61855242295;4490.348899409632;4514.548545145591;4532.410786597721;4536.705114167568;5990.46085852258;6202.255729312511;6280.560360774702;6319.936800632118;6382.712777924911;6088.36072212429;6153.600015998945;6157.475435071599;6182.876342473035;6192.81421557354;6029.57792954489;6110.541709371726;6113.266408031065;6140.785072522619;6177.598456100339;6076.848078798065;6119.19483752303;6123.9693801532485;6141.969290153521;6147.661878596541;6127.84680382994;6154.569180157346;6191.018873960289;6194;6204.162438070803;5855.619384739978;6031.336552979081;6045.75816967334;6051.334353221054;6070.514358851193 07:19:16 INFO - PROCESS | 12748 | |5;yui.html;751.6692655231698;780.009388077142;858.2704701004147;865.3077537969631;883.8011447424328;16198.514044578667;16434.34262629495;16664.83335166648;16666.58333541662;17847.375341863037;17744.82255177448;17893.28426862924;18063.819361806378;18070.819291807078;18071.186796594153;860.9182127697859;888.138505649519;902.9458232506071;913.808100298938;914.7941713114529;886.2334081019903;892.5492626223748;894.114826321943;900.4822227219342;901.1484147480634;941.9434833909953;946.153192892361;946.7822400847809;954.5942974235957;954.7994921066585;938.8028514012066;941.0542404883083;941.1200527506769;943.1747221181466;944.338962726091;692.069166970424;692.8475735338218;693.7641201991297;694.4964900447186;695.7008486350918;15370.077795332205;15547.689046219013;15604.219789010504;15635;15711.292991815395;15399.923000384926;15488.612784680361;15615.31381377637;16124.919375403166;16412.343506259735;13063.729628268713;13457.192568445924;13490.730185396334;13608.455661773518;13746.450141994308;4094.9385759213637;4145.937810932838;4155.729877557949;4179;4258.105797782469;93081.5345923266;94924;95308.52345738227;96017.51991239999;98050;12591.874081259253;12628.936855315664;12686.619401417973;12727.554535591395;12762.10665253442;22204.555908882056;22331.330060098222;22367.44081397978;22394.32817015492;22396.776032239795;9178.128077832594;9191.126842949909;9244.260459163384;9268.582913768896;9381.484018378918;5247.501487358695;5430.456954304538;5446.3191253998375;5449.482299181572;5449.618526703092;4066.1461093170174;4215.178040282116;4218.599233072853;4219.156168766259;4219.493660760667;685.2496516314627;689.1592257445908;697.1494776372817;702.220535205921;713.6217804563589;753.2806170107534;790.6323559544725;800.7077416742846;810.2383759266271;818.8444195602935;10009.649662261785;10878.347299162075;11274.379909105079;11345.149113816497;11465.885341146648;803.4255507312183;803.8794180872916;824.3034635732796;828.159418190537;828.5484410996022;5402.756875940592;5525.751341189576;5645.633033852787;5660.915086273709;5705.144228365778;135919.32040339735;136057;136278.31860840632;136474.31762841123;138691;71711.6414417935;93300.53349733209;118844.02889927648;118887;121201.39399302947;93688;94980.05019949851;97031;97175.02824971802;97692;10879;10900.776772699275;11292.15308851823;11311.604093856675;11378;54629;54812.451875480736;55753;56882.7155864218;57130.71434642967;77152.22847771675;83584;84234.15765842509;85399;87422.56288718515;13637.931810340884;13681.521146759816;13754.243516606683;14002.089864158797;14062.507812226522;15346.232688365313;15364.615884602974;15490.767638485206;16028;16490.83509164941;13927;14048.508302209373;14768.77846832277;15217.086974781549;15418.845811541742;13776.448942042607;13935.790963135763;14025.158490490603;14070.155790652594;14669.92665036668;14244.786328205293;14610;14892.851071489147;15129.39482420691;15274.923625381802;11548.076153907836;11683.123765717435;11906.166568340133;11939.880601194225;12034.398280086136;12075.51697932073;12250.387480626112;12518.186317889307;12649.810252846399;12711.80932285998 07:19:16 INFO - PROCESS | 12748 | __end_tp_report 07:19:16 INFO - PROCESS | 12748 | __start_cc_report 07:19:16 INFO - PROCESS | 12748 | _x_x_mozilla_cycle_collect,327 07:19:16 INFO - PROCESS | 12748 | __end_cc_report 07:19:16 INFO - PROCESS | 12748 | __startTimestamp1472739556162__endTimestamp 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | ------- Summary: start ------- 07:19:16 INFO - PROCESS | 12748 | Number of tests: 6 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | [#0] dojo.html Cycles:165 Average:5884.57 Median:6136.95 stddev:691.39 (11.3%) stddev-sans-first:664.13 07:19:16 INFO - PROCESS | 12748 | Values: 3342.8 3363.9 3366.6 3388.6 3393.5 5832.9 5912.4 5916.4 5954.0 5956.3 5820.2 5881.4 5904.1 5911.6 5960.6 5334.9 5397.5 5401.6 5447.2 5450.3 5256.6 5278.2 5283.9 5295.2 5341.2 5293.8 5324.3 5342.8 5356.8 5394.1 5373.7 5380.5 5386.5 5386.7 5395.1 5176.8 5195.0 5195.8 5289.8 5389.4 5783.9 5890.1 5904.6 5921.0 5945.2 6329.7 6356.3 6373.7 6383.4 6398.7 6142.6 6277.7 6421.2 6423.4 6424.9 5872.1 5936.7 5941.3 5961.2 5969.0 6475.2 6487.5 6539.6 6546.5 6547.3 5970.7 6125.7 6280.6 6284.0 6292.9 6222.6 6363.1 6366.0 6392.1 6393.2 6356.7 6368.0 6371.3 6371.8 6373.4 6114.6 6180.3 6368.2 6370.6 6374.4 4759.8 4810.4 4824.1 4834.2 4841.1 4273.6 4274.9 4276.7 4303.1 4304.0 5915.2 5980.2 6070.0 6107.4 6139.1 6275.9 6430.5 6445.6 6452.2 6459.4 6075.5 6124.3 6137.7 6138.7 6141.2 5322.0 5400.0 5494.8 5506.6 5533.6 6433.8 6556.2 6563.0 6573.8 6578.6 6372.3 6382.5 6388.5 6389.7 6394.3 6289.4 6441.4 6530.6 6535.1 6595.1 6191.5 6322.2 6326.5 6335.3 6336.7 6422.5 6429.3 6434.9 6440.0 6441.5 6047.3 6125.2 6285.7 6291.7 6301.6 6115.7 6191.1 6228.5 6239.1 6254.1 6108.1 6152.7 6153.0 6157.2 6164.2 5985.7 6130.5 6171.7 6195.2 6255.4 6020.2 6127.8 6136.9 6143.1 6150.3 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | [#1] ext.html Cycles:180 Average:59142.00 Median:24874.28 stddev:112573.07 (452.6%) stddev-sans-first:112803.04 07:19:16 INFO - PROCESS | 12748 | Values: 594.4 637.7 652.9 661.2 701.6 42364.6 47282.3 47315.5 47393.0 47507.3 47202.0 47740.5 47794.3 47808.0 47877.3 9234.2 9361.5 9371.3 9385.2 9390.4 9272.3 9300.5 9345.0 9376.7 9426.2 9454.1 9474.8 9484.6 9520.9 9574.8 9379.3 9404.6 9425.5 9443.3 9446.8 9246.2 9297.0 9308.5 9313.5 9336.3 44719.0 45223.3 45314.5 45381.1 45410.5 3165.4 3725.5 3769.0 3794.1 3839.3 33701.3 34258.5 34291.5 34324.7 34392.0 4479.7 4525.2 4539.5 4734.9 4736.0 334806.0 334927.0 338291.0 338475.0 340344.0 38796.2 39803.2 39887.4 39888.0 39936.8 39681.4 41216.6 41302.8 41470.2 41651.6 17691.7 17857.6 17881.6 17919.4 17959.0 13990.2 14003.5 14103.4 14115.2 14217.6 9279.9 9290.4 9325.5 9339.5 9481.5 3558.8 3564.1 3614.1 3635.7 3639.2 1064.1 1096.3 1106.9 1157.0 1166.3 35115.3 35263.5 35478.6 35532.6 35557.8 1132.6 1163.3 1184.1 1218.4 1225.3 17922.1 18340.5 18513.6 18523.3 18838.2 331885.0 335074.0 335131.0 335312.0 338425.0 64691.0 65331.7 65678.7 65757.7 65944.7 1459.3 1465.6 1466.7 1478.9 1494.0 35333.6 36231.8 36250.8 36355.1 36501.0 58328.7 62552.7 63923.4 64497.4 68966.3 557642.0 560293.0 562672.0 563789.0 563928.0 49461.5 49585.8 50049.7 50103.7 50518.5 54579.0 54884.0 55016.4 55189.2 55535.4 45656.3 47059.3 47753.8 47851.8 48700.3 47790.5 47959.5 47991.5 48619.8 48642.5 40153.4 44699.3 45497.5 45732.8 46018.0 39660.2 40640.0 40769.6 40930.8 41333.0 31084.0 31227.5 31563.2 31654.5 31811.2 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | [#2] jquery.html Cycles:180 Average:30185.29 Median:2907.58 stddev:83886.26 (2885.1%) stddev-sans-first:84091.82 07:19:16 INFO - PROCESS | 12748 | Values: 422.5 423.2 425.2 425.2 457.8 20393.7 21182.4 21201.4 21587.0 22220.9 19567.4 20271.6 20579.6 20671.5 20879.0 5746.8 5964.8 5968.5 6000.6 6008.8 5450.9 5695.3 5730.9 5891.3 5915.2 5730.8 5744.3 5765.3 5794.0 5806.8 5737.5 5802.2 6118.8 6252.7 6257.6 5804.3 5888.7 5911.6 5917.7 6021.0 13199.0 13337.3 13400.9 24596.8 24823.1 2473.3 2554.9 2708.5 3106.6 3156.2 10460.4 10690.1 14460.0 14764.4 15313.9 1939.4 2024.8 2034.1 2045.4 2067.6 88892.0 89266.0 89712.1 90222.1 90268.1 11828.3 11891.4 12126.0 12211.0 12260.2 16812.2 16826.0 16830.2 16885.8 16910.9 8797.9 9049.2 9107.9 9178.7 9209.4 8533.9 8806.6 8912.5 8933.5 9212.1 4833.9 4886.9 5079.3 5176.5 5201.4 2023.7 2070.5 2162.8 2166.7 2186.7 986.3 1039.6 1079.4 1091.7 1092.3 20766.2 20929.4 20972.6 21199.8 21341.1 1028.3 1030.9 1039.5 1041.2 1074.7 11056.9 12305.1 12355.7 12527.4 12552.4 494883.0 508148.0 510582.0 510616.0 515412.0 90989.5 91249.0 93972.5 98565.0 99702.0 485.5 508.2 529.6 530.4 530.7 11750.8 11771.3 11796.6 12322.5 12377.8 41071.8 42770.8 43632.1 44189.3 45705.8 61240.7 61551.4 62009.7 63045.4 63092.4 8554.4 8783.5 8804.2 8814.1 9112.8 8508.0 12761.2 13776.9 14059.8 14215.6 12023.3 12308.4 12336.4 12409.5 12520.2 11903.9 12162.6 12179.4 12242.1 12541.3 12430.1 12432.9 12517.3 12559.2 12694.8 8613.0 8757.6 8861.5 9002.4 9123.5 10178.8 12111.8 12234.2 12491.7 12519.9 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | [#3] mootools.html Cycles:180 Average:6466.11 Median:4599.20 stddev:3759.41 (81.7%) stddev-sans-first:3751.79 07:19:16 INFO - PROCESS | 12748 | Values: 1547.9 1571.1 1748.2 1863.9 1886.2 8644.6 9396.0 9589.2 10119.3 10120.3 9552.9 9923.6 9963.9 9973.0 9997.1 2525.7 2550.4 2564.3 2572.0 2573.4 2454.4 2581.1 2581.5 2586.8 2603.9 2502.7 2534.0 2571.7 2576.8 2584.4 2513.3 2515.3 2517.1 2552.0 2558.4 2469.9 2486.2 2487.4 2501.3 2518.3 9881.5 9900.7 10028.3 10030.3 10081.2 5479.3 5577.3 5591.2 5598.7 5600.7 3666.4 3741.5 3774.5 3819.9 3877.4 1199.3 1228.2 1254.6 1264.3 1269.1 14217.9 14256.9 14292.9 14316.5 14336.4 5064.6 5171.8 5255.8 5257.8 5292.0 11850.4 11860.2 11939.0 11959.9 12003.5 5911.4 5914.5 5917.4 5925.5 5928.9 4733.3 5172.9 5322.8 5373.4 5436.0 4397.5 4438.6 4479.8 4518.0 4594.3 1360.4 1376.3 1383.1 1387.9 1404.2 1018.5 1078.7 1079.8 1083.9 1091.6 6570.3 6653.5 6739.7 6766.1 6848.9 483.1 485.4 489.9 491.1 496.9 4543.6 4604.1 4667.6 4792.0 4844.2 12036.5 12224.9 12344.8 12345.6 12398.8 11480.4 11981.9 12014.7 12440.8 12511.2 9019.8 9143.6 9150.5 9158.4 9251.6 6373.3 6627.2 6628.9 6645.4 6668.9 11314.6 11345.9 11359.4 11404.7 11454.4 11249.2 11325.5 11529.0 11622.3 11676.5 7443.1 8499.0 8799.8 8836.9 8893.6 9162.8 9388.8 9456.4 9479.1 9506.4 8214.3 8426.9 8448.8 8497.4 8707.5 7984.7 8009.8 8361.0 8432.0 8463.5 7473.1 7787.6 7820.6 7842.5 7861.4 5613.8 5695.4 5715.6 5761.2 5794.8 5820.4 5881.4 5949.0 5953.7 6063.9 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | [#4] prototype.html Cycles:180 Average:5070.89 Median:5652.25 stddev:1497.67 (26.5%) stddev-sans-first:1459.20 07:19:16 INFO - PROCESS | 12748 | Values: 341.9 361.6 362.2 365.8 368.0 5673.6 5772.3 5779.5 5819.3 5857.3 5757.8 5784.4 5795.9 5804.1 5821.6 4588.2 4591.7 4606.7 4621.3 4636.7 4480.6 4580.3 4584.6 4595.9 4622.6 4524.2 4539.0 4545.8 4559.1 4567.1 4561.2 4562.5 4582.7 4586.2 4609.6 3884.0 3942.1 3945.4 3953.7 3972.8 5611.7 5617.6 5650.9 5653.6 5660.3 5091.2 5153.1 5179.1 5181.8 5193.9 6038.3 6043.3 6048.5 6075.6 6079.2 4351.7 4353.2 4353.3 4363.7 4370.0 6095.7 6285.4 6337.4 6339.5 6362.7 4466.1 4466.8 4470.6 4478.6 4486.8 4513.6 4521.2 4553.3 4554.5 4590.0 4956.3 5138.6 5217.8 5221.6 5233.2 5979.4 6065.6 6132.4 6148.5 6180.3 1535.8 1577.5 1578.3 1582.1 1584.2 969.8 985.7 1000.8 1006.4 1025.2 5179.8 5313.5 5315.8 5321.2 5370.6 5521.8 5557.4 5571.5 5577.2 5612.5 5017.4 5281.7 5289.9 5360.9 5381.9 2940.9 2950.7 2968.6 3005.9 3006.4 5666.4 5678.3 5708.7 5754.5 5776.4 5984.4 6375.2 6442.3 6451.2 6474.4 6505.4 6521.5 6545.9 6561.0 6574.6 6081.3 6286.8 6298.5 6306.4 6321.6 6225.8 6239.5 6256.1 6263.2 6272.8 6361.4 6464.9 6506.9 6524.9 6542.7 4487.6 4490.3 4514.5 4532.4 4536.7 5990.5 6202.3 6280.6 6319.9 6382.7 6088.4 6153.6 6157.5 6182.9 6192.8 6029.6 6110.5 6113.3 6140.8 6177.6 6076.8 6119.2 6124.0 6142.0 6147.7 6127.8 6154.6 6191.0 6194.0 6204.2 5855.6 6031.3 6045.8 6051.3 6070.5 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - PROCESS | 12748 | [#5] yui.html Cycles:180 Average:23410.11 Median:4150.83 stddev:34465.10 (830.3%) stddev-sans-first:34519.79 07:19:16 INFO - PROCESS | 12748 | Values: 751.7 780.0 858.3 865.3 883.8 16198.5 16434.3 16664.8 16666.6 17847.4 17744.8 17893.3 18063.8 18070.8 18071.2 860.9 888.1 902.9 913.8 914.8 886.2 892.5 894.1 900.5 901.1 941.9 946.2 946.8 954.6 954.8 938.8 941.1 941.1 943.2 944.3 692.1 692.8 693.8 694.5 695.7 15370.1 15547.7 15604.2 15635.0 15711.3 15399.9 15488.6 15615.3 16124.9 16412.3 13063.7 13457.2 13490.7 13608.5 13746.5 4094.9 4145.9 4155.7 4179.0 4258.1 93081.5 94924.0 95308.5 96017.5 98050.0 12591.9 12628.9 12686.6 12727.6 12762.1 22204.6 22331.3 22367.4 22394.3 22396.8 9178.1 9191.1 9244.3 9268.6 9381.5 5247.5 5430.5 5446.3 5449.5 5449.6 4066.1 4215.2 4218.6 4219.2 4219.5 685.2 689.2 697.1 702.2 713.6 753.3 790.6 800.7 810.2 818.8 10009.6 10878.3 11274.4 11345.1 11465.9 803.4 803.9 824.3 828.2 828.5 5402.8 5525.8 5645.6 5660.9 5705.1 135919.3 136057.0 136278.3 136474.3 138691.0 71711.6 93300.5 118844.0 118887.0 121201.4 93688.0 94980.1 97031.0 97175.0 97692.0 10879.0 10900.8 11292.2 11311.6 11378.0 54629.0 54812.5 55753.0 56882.7 57130.7 77152.2 83584.0 84234.2 85399.0 87422.6 13637.9 13681.5 13754.2 14002.1 14062.5 15346.2 15364.6 15490.8 16028.0 16490.8 13927.0 14048.5 14768.8 15217.1 15418.8 13776.4 13935.8 14025.2 14070.2 14669.9 14244.8 14610.0 14892.9 15129.4 15274.9 11548.1 11683.1 11906.2 11939.9 12034.4 12075.5 12250.4 12518.2 12649.8 12711.8 07:19:16 INFO - PROCESS | 12748 | -------- Summary: end -------- 07:19:16 INFO - PROCESS | 12748 | 07:19:16 INFO - TEST-INFO | 12748: exit 0 07:19:16 INFO - TEST-OK | dromaeo_css | took 1111348ms 07:19:16 INFO - TEST-START | kraken 07:19:16 INFO - operating with platform_type : linux_ 07:19:16 INFO - Initialising browser for kraken test... 07:19:16 INFO - TEST-INFO | started process 13009 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyv8tGQ/profile http://localhost:51320/getInfo.html) 07:19:21 INFO - PROCESS | 13009 | __metrics Screen width/height:1600/1200 07:19:21 INFO - PROCESS | 13009 | colorDepth:24 07:19:21 INFO - PROCESS | 13009 | Browser inner width/height: 1024/697 07:19:21 INFO - PROCESS | 13009 | __metrics 07:19:21 ERROR - PROCESS | 13009 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:22 INFO - TEST-INFO | 13009: exit 0 07:19:22 INFO - Browser initialized. 07:19:22 INFO - Running cycle 1/1 for kraken test... 07:19:22 INFO - TEST-INFO | started process 13135 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyv8tGQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:34 INFO - PROCESS | 13135 | RSS: Main: 152416256 07:19:34 INFO - PROCESS | 13135 | 07:19:37 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:37 INFO - PROCESS | 13135 | RSS: Main: 157986816 07:19:37 INFO - PROCESS | 13135 | 07:19:40 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-dft) 07:19:41 INFO - PROCESS | 13135 | RSS: Main: 156618752 07:19:41 INFO - PROCESS | 13135 | 07:19:44 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-fft) 07:19:44 INFO - PROCESS | 13135 | RSS: Main: 157515776 07:19:44 INFO - PROCESS | 13135 | 07:19:46 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-oscillator) 07:19:47 INFO - PROCESS | 13135 | RSS: Main: 156811264 07:19:47 INFO - PROCESS | 13135 | 07:19:49 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:50 INFO - PROCESS | 13135 | RSS: Main: 156643328 07:19:50 INFO - PROCESS | 13135 | 07:19:53 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51320/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:53 INFO - PROCESS | 13135 | RSS: Main: 156540928 07:19:53 INFO - PROCESS | 13135 | 07:19:57 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51320/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:57 INFO - PROCESS | 13135 | RSS: Main: 156823552 07:19:57 INFO - PROCESS | 13135 | 07:20:00 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51320/tests/kraken/driver.html?testName=json-parse-financial) 07:20:01 INFO - PROCESS | 13135 | RSS: Main: 157360128 07:20:01 INFO - PROCESS | 13135 | 07:20:02 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:03 INFO - PROCESS | 13135 | RSS: Main: 156336128 07:20:03 INFO - PROCESS | 13135 | 07:20:05 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:05 INFO - PROCESS | 13135 | RSS: Main: 156381184 07:20:05 INFO - PROCESS | 13135 | 07:20:07 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:08 INFO - PROCESS | 13135 | RSS: Main: 156905472 07:20:08 INFO - PROCESS | 13135 | 07:20:11 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:11 INFO - PROCESS | 13135 | RSS: Main: 157302784 07:20:11 INFO - PROCESS | 13135 | 07:20:14 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:15 INFO - PROCESS | 13135 | RSS: Main: 157650944 07:20:15 INFO - PROCESS | 13135 | 07:20:16 INFO - PROCESS | 13135 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51320/tests/kraken/driver.html?testName=ai-astar) 07:20:17 INFO - PROCESS | 13135 | RSS: Main: 158638080 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | __start_tp_report 07:20:17 INFO - PROCESS | 13135 | _x_x_mozilla_page_load 07:20:17 INFO - PROCESS | 13135 | _x_x_mozilla_page_load_details 07:20:17 INFO - PROCESS | 13135 | |i|pagename|runs| 07:20:17 INFO - PROCESS | 13135 | |0;ai-astar;101;109;105;110;111;110;108;109;111;111 07:20:17 INFO - PROCESS | 13135 | |1;audio-beat-detection;149;120;144;171;116;136;140;117;173;116 07:20:17 INFO - PROCESS | 13135 | |2;audio-dft;163;160;162;161;161;162;160;161;160;163 07:20:17 INFO - PROCESS | 13135 | |3;audio-fft;75;76;75;180;75;75;75;76;190;76 07:20:17 INFO - PROCESS | 13135 | |4;audio-oscillator;83;84;146;83;87;83;140;83;82;151 07:20:17 INFO - PROCESS | 13135 | |5;imaging-gaussian-blur;118;118;118;118;117;118;117;117;118;117 07:20:17 INFO - PROCESS | 13135 | |6;imaging-darkroom;147;148;148;146;147;146;146;147;149;146 07:20:17 INFO - PROCESS | 13135 | |7;imaging-desaturate;95;94;96;95;95;98;95;96;96;95 07:20:17 INFO - PROCESS | 13135 | |8;json-parse-financial;52;55;53;52;51;51;51;53;50;51 07:20:17 INFO - PROCESS | 13135 | |9;json-stringify-tinderbox;64;59;58;59;59;58;59;61;60;59 07:20:17 INFO - PROCESS | 13135 | |10;stanford-crypto-aes;72;73;72;73;72;82;71;71;76;72 07:20:17 INFO - PROCESS | 13135 | |11;stanford-crypto-ccm;122;131;121;118;117;131;117;123;159;125 07:20:17 INFO - PROCESS | 13135 | |12;stanford-crypto-pbkdf2;158;161;154;153;152;161;154;156;153;155 07:20:17 INFO - PROCESS | 13135 | |13;stanford-crypto-sha256-iterative;63;62;64;60;61;62;62;62;64;65 07:20:17 INFO - PROCESS | 13135 | __end_tp_report 07:20:17 INFO - PROCESS | 13135 | __start_cc_report 07:20:17 INFO - PROCESS | 13135 | _x_x_mozilla_cycle_collect,884 07:20:17 INFO - PROCESS | 13135 | __end_cc_report 07:20:17 INFO - PROCESS | 13135 | __startTimestamp1472739617014__endTimestamp 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | ------- Summary: start ------- 07:20:17 INFO - PROCESS | 13135 | Number of tests: 14 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.21 (2.9%) stddev-sans-first:1.94 07:20:17 INFO - PROCESS | 13135 | Values: 101.0 109.0 105.0 110.0 111.0 110.0 108.0 109.0 111.0 111.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:142.00 stddev:21.63 (15.2%) stddev-sans-first:22.59 07:20:17 INFO - PROCESS | 13135 | Values: 149.0 120.0 144.0 171.0 116.0 136.0 140.0 117.0 173.0 116.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.16 (0.7%) stddev-sans-first:1.05 07:20:17 INFO - PROCESS | 13135 | Values: 163.0 160.0 162.0 161.0 161.0 162.0 160.0 161.0 160.0 163.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#3] audio-fft Cycles:10 Average:97.30 Median:75.00 stddev:46.28 (61.7%) stddev-sans-first:48.38 07:20:17 INFO - PROCESS | 13135 | Values: 75.0 76.0 75.0 180.0 75.0 75.0 75.0 76.0 190.0 76.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:83.00 stddev:30.14 (36.3%) stddev-sans-first:31.15 07:20:17 INFO - PROCESS | 13135 | Values: 83.0 84.0 146.0 83.0 87.0 83.0 140.0 83.0 82.0 151.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:20:17 INFO - PROCESS | 13135 | Values: 118.0 118.0 118.0 118.0 117.0 118.0 117.0 117.0 118.0 117.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 07:20:17 INFO - PROCESS | 13135 | Values: 147.0 148.0 148.0 146.0 147.0 146.0 146.0 147.0 149.0 146.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:1.08 (1.1%) stddev-sans-first:1.13 07:20:17 INFO - PROCESS | 13135 | Values: 95.0 94.0 96.0 95.0 95.0 98.0 95.0 96.0 96.0 95.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.45 (2.8%) stddev-sans-first:1.54 07:20:17 INFO - PROCESS | 13135 | Values: 52.0 55.0 53.0 52.0 51.0 51.0 51.0 53.0 50.0 51.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:1.78 (3.0%) stddev-sans-first:0.93 07:20:17 INFO - PROCESS | 13135 | Values: 64.0 59.0 58.0 59.0 59.0 58.0 59.0 61.0 60.0 59.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:72.50 stddev:3.34 (4.6%) stddev-sans-first:3.50 07:20:17 INFO - PROCESS | 13135 | Values: 72.0 73.0 72.0 73.0 72.0 82.0 71.0 71.0 76.0 72.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:124.00 stddev:12.54 (10.1%) stddev-sans-first:13.20 07:20:17 INFO - PROCESS | 13135 | Values: 122.0 131.0 121.0 118.0 117.0 131.0 117.0 123.0 159.0 125.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:155.50 stddev:3.27 (2.1%) stddev-sans-first:3.36 07:20:17 INFO - PROCESS | 13135 | Values: 158.0 161.0 154.0 153.0 152.0 161.0 154.0 156.0 153.0 155.0 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - PROCESS | 13135 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:1.51 (2.4%) stddev-sans-first:1.59 07:20:17 INFO - PROCESS | 13135 | Values: 63.0 62.0 64.0 60.0 61.0 62.0 62.0 62.0 64.0 65.0 07:20:17 INFO - PROCESS | 13135 | -------- Summary: end -------- 07:20:17 INFO - PROCESS | 13135 | 07:20:17 INFO - TEST-INFO | 13135: exit 0 07:20:18 INFO - TEST-OK | kraken | took 61099ms 07:20:18 INFO - SUITE-END | took 1172s 07:20:18 INFO - Completed test suite (00:19:32) 07:20:18 INFO - javascript benchmark 07:20:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7774.713376154836, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3342.766006379553, 3363.865445382185, 3366.562346894904, 3388.6272510023878, 3393.4740115282125, 5832.883342333156, 5912.408759124085, 5916.437938395857, 5954.047352423608, 5956.255468066492, 5820.243368362107, 5881.382454842244, 5904.0553511438175, 5911.556633252512, 5960.6423614583155, 5334.893302133955, 5397.514223719864, 5401.594880383967, 5447.210154527591, 5450.29146210992, 5256.5794736421185, 5278.155495120782, 5283.943211357725, 5295.179247216687, 5341.1988201769655, 5293.788248470076, 5324.281222035014, 5342.813001544947, 5356.758945847446, 5394.05604019297, 5373.704446255435, 5380.482679984239, 5386.542143917772, 5386.703731294777, 5395.082835917903, 5176.7929282828645, 5195.0, 5195.792168313263, 5289.788408463657, 5389.353277606673, 5783.8554036149435, 5890.087036509348, 5904.645721256696, 5920.97039514804, 5945.227120474354, 6329.651869147195, 6356.300806911282, 6373.681315934185, 6383.361663833626, 6398.712057957357, 6142.590862071548, 6277.654728989904, 6421.165248517709, 6423.389777971085, 6424.871502569969, 5872.06047032477, 5936.673482958478, 5941.346451890288, 5961.195238642815, 5969.044952807573, 6475.190601174853, 6487.54587178893, 6539.6403197824575, 6546.541742078104, 6547.34526547351, 5970.731317090741, 6125.723854043132, 6280.623162610258, 6283.96858015707, 6292.937070629327, 6222.62664240147, 6363.140975968185, 6365.96817015912, 6392.137061496638, 6393.232812062489, 6356.713947872357, 6368.044793281045, 6371.267304259976, 6371.776987805497, 6373.362663733589, 6114.601521188728, 6180.289266734383, 6368.172137622079, 6370.617762934239, 6374.426301632782, 4759.809607615656, 4810.374651295309, 4824.131656301899, 4834.226523756182, 4841.12859685253, 4273.615374616299, 4274.850380236677, 4276.743395396286, 4303.07483890965, 4304.010077682151, 5915.1718759373725, 5980.222571065734, 6069.969650151809, 6107.4197951194565, 6139.079138129227, 6275.874482510324, 6430.517711171681, 6445.581037232565, 6452.22573291208, 6459.418652321314, 6075.453209211192, 6124.295705993779, 6137.662428566382, 6138.654433333754, 6141.170941922784, 5322.042032434199, 5399.999999999922, 5494.80768173112, 5506.587005974568, 5533.6403133796175, 6433.806985790434, 6556.24603170632, 6563.048357988065, 6573.835654108686, 6578.63817490033, 6372.330905254881, 6382.489400848014, 6388.520860935356, 6389.7124629390855, 6394.328595497405, 6289.40250676185, 6441.3880681335195, 6530.640814755234, 6535.085088087577, 6595.142631457879, 6191.504679625529, 6322.178116844781, 6326.4622507087415, 6335.2714437838395, 6336.7148478319505, 6422.454091402096, 6429.260635027032, 6434.873897068088, 6440.0, 6441.484681225585, 6047.334793172836, 6125.204929265209, 6285.717142728679, 6291.653959032297, 6301.558890877778, 6115.666295175977, 6191.071339299141, 6228.532860035606, 6239.0953311770445, 6254.061890716429, 6108.08378743192, 6152.723127459365, 6152.969235153795, 6157.230346206724, 6164.198654174928, 5985.700714964321, 6130.489860652556, 6171.722272497838, 6195.2255968004, 6255.374462553708, 6020.217371741645, 6127.780302992555, 6136.94800169746, 6143.109249158758, 6150.323464419], "value": 5836.138389537674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.3996563470896, 637.7321524959517, 652.9085927970085, 661.1966460750189, 701.6351497221448, 42364.57635423645, 47282.29076563854, 47315.52684473158, 47393.0, 47507.28739068917, 47202.0, 47740.52259477404, 47794.283085753654, 47808.0, 47877.28184077242, 9234.168924796784, 9361.48511831849, 9371.29715271354, 9385.202257808094, 9390.436573805559, 9272.304577156707, 9300.488473133975, 9345.0, 9376.671816486425, 9426.245900327956, 9454.05459454056, 9474.763130921714, 9484.573194206243, 9520.8571871422, 9574.80850382992, 9379.296552758537, 9404.62381504739, 9425.528723563795, 9443.338966272364, 9446.763830904283, 9246.167844893991, 9297.023812499725, 9308.534573271378, 9313.534323283877, 9336.299777516713, 44719.0, 45223.321650175276, 45314.54685453136, 45381.09237815225, 45410.54589454096, 3165.3511030238747, 3725.4784330193793, 3768.9811550942345, 3794.0704527390967, 3839.308924393608, 33701.32597348064, 34258.48612270818, 34291.48562771561, 34324.6567534324, 34392.0, 4479.664025198123, 4525.230710779176, 4539.4779600346055, 4734.905301893943, 4735.976320118378, 334806.0, 334927.0, 338291.0, 338475.0, 340344.0, 38796.22407551833, 39803.20393592112, 39887.401688974685, 39888.0, 39936.800315998815, 39681.404778928336, 41216.587834121274, 41302.79348603298, 41470.170596588505, 41651.583484164774, 17691.73462398065, 17857.642847142983, 17881.552961176076, 17919.37282195117, 17959.0, 13990.160590364407, 14003.509877154454, 14103.365348559339, 14115.22366269865, 14217.644558885904, 9279.860802087975, 9290.442573445614, 9325.533723313807, 9339.533023348942, 9481.525923703924, 3558.839852206657, 3564.0733409313766, 3614.1145419372424, 3635.709143268532, 3639.1993761372455, 1064.079571170947, 1096.2655021135859, 1106.8727096383857, 1156.9652910412701, 1166.2618526079523, 35115.29769404598, 35263.471047934305, 35478.645213547534, 35532.64467355345, 35557.822210889295, 1132.6360708695163, 1163.3194581169942, 1184.1296646964474, 1218.4456072486996, 1225.2770865189582, 17922.096090814593, 18340.54148646268, 18513.629727405376, 18523.259069637334, 18838.15228314729, 331885.0, 335074.0, 335131.0, 335312.0, 338425.0, 64691.0, 65331.67334163299, 65678.67160664262, 65757.67121164364, 65944.6702766483, 1459.3287087939666, 1465.560331900447, 1466.74331991901, 1478.9260536973109, 1494.0438119603461, 35333.646663533036, 36231.818840905624, 36250.8187459061, 36355.091122722144, 36501.0, 58328.708356457944, 62552.68723656535, 63923.36076639174, 64497.355026449135, 68966.31033689599, 557642.0, 560293.0, 562672.0, 563789.0, 563928.0, 49461.50538494569, 49585.75207123941, 50049.749751252464, 50103.74948125236, 50518.49481505138, 54579.0, 54884.0, 55016.44983550113, 55189.17216241679, 55535.44464555303, 45656.31515527336, 47059.29411058768, 47753.761231193625, 47851.76074119746, 48700.269495958295, 47790.52209477861, 47959.520404795505, 47991.520084800104, 48619.75690121527, 48642.5135748638, 40153.39769903512, 44699.32951005803, 45497.545024549334, 45732.77133614311, 46018.0, 39660.20679586334, 40640.0, 40769.59230407658, 40930.79534602308, 41333.0, 31084.0, 31227.53158702576, 31563.21091972719, 31654.525182122747, 31811.20471988219], "value": 20487.97905869703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.4571425717955, 423.1663622663353, 425.156065210557, 425.2218440254335, 457.7551010209539, 20393.694094588576, 21182.364529064114, 21201.363959081234, 21587.0, 22220.88889555554, 19567.412977610693, 20271.59456810863, 20579.588408231826, 20671.483212919684, 20879.0, 5746.770129194838, 5964.79123230687, 5968.462838344548, 6000.579959402844, 6008.8497787555225, 5450.890982180354, 5695.345035320929, 5730.885382292352, 5891.322497912731, 5915.201447804534, 5730.7707691692285, 5744.310682718059, 5765.336986246593, 5793.971030144843, 5806.767729290823, 5737.454941780545, 5802.187693722861, 6118.816435506919, 6252.749890004395, 6257.5932564383165, 5804.332501762309, 5888.735006924699, 5911.586188966774, 5917.704114794243, 6020.969895150496, 13199.01007424447, 13337.252049854002, 13400.865991340059, 24596.754032459805, 24823.13119040843, 2473.295110893405, 2554.923352299434, 2708.526007948601, 3106.6427360853563, 3156.2425017995747, 10460.424676642782, 10690.086080285193, 14460.0, 14764.409423623043, 15313.923430382776, 1939.4472575315967, 2024.7975202479633, 2034.074496104269, 2045.3659365596714, 2067.5968186203695, 88892.0, 89266.0, 89712.10287897168, 90222.09777902202, 90268.09731902728, 11828.290302581872, 11891.405429728478, 12126.0, 12211.0, 12260.203086799329, 16812.159392030593, 16826.0, 16830.242639081273, 16885.831141688424, 16910.915445422695, 8797.912020879709, 9049.185573298435, 9107.863382049141, 9178.67874624385, 9209.35534512591, 8533.871991920127, 8806.559672016374, 8912.546321329897, 8933.46399216049, 9212.078792120734, 4833.927491087637, 4886.853394398174, 5079.288899554065, 5176.472940482567, 5201.420203442648, 2023.7065625484518, 2070.523779530686, 2162.7945345192497, 2166.653335466318, 2186.6610675345346, 986.2997271936862, 1039.6212309992525, 1079.4109585627818, 1091.7379828841022, 1092.2954694222183, 20766.169353226003, 20929.372118836458, 20972.580548388945, 21199.78800211978, 21341.14635414597, 1028.3367228137809, 1030.907218350352, 1039.5270152080936, 1041.2398948767443, 1074.6990842564094, 11056.889431105586, 12305.138640295272, 12355.6911077222, 12527.37363131858, 12552.435140418704, 494883.0, 508148.0, 510582.0, 510616.0, 515412.0, 90989.54505227564, 91249.0, 93972.53013734888, 98565.0, 99702.0, 485.51206037931655, 508.22495694066106, 529.6424913183586, 530.4138926486254, 530.6603773584986, 11750.764984700429, 11771.293722376684, 11796.646100616996, 12322.507099715911, 12377.814332784836, 41071.7946410266, 42770.78614606907, 43632.12735745331, 44189.33715994327, 45705.771471142434, 61240.69379653073, 61551.384486154566, 62009.68995154995, 63045.36954630578, 63092.36907631048, 8554.443961142506, 8783.472991620523, 8804.207621314112, 8814.118588141135, 9112.817743645222, 8508.0, 12761.234325940102, 13776.862231377558, 14059.789103163255, 14215.644608884448, 12023.338716370683, 12308.384580770746, 12336.444859981502, 12409.503619855466, 12520.248785072925, 11903.880961190624, 12162.574309899112, 12179.391030448265, 12242.143049986415, 12541.31022793755, 12430.12989090755, 12432.875671243171, 12517.311547864954, 12559.246445213317, 12694.809577856524, 8613.0, 8757.60590773404, 8861.468311901306, 9002.414843035182, 9123.452592844451, 10178.847317290394, 12111.818322725343, 12234.204776689487, 12491.687707807378, 12519.93740031294], "value": 9467.668576164364, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1547.8916475846693, 1571.065216196363, 1748.2482532511024, 1863.8974856382893, 1886.2172198537598, 8644.610992505337, 9395.953020234907, 9589.18491928186, 10119.342242754234, 10120.34217775845, 9552.856707149398, 9923.603055877757, 9963.850542241873, 9973.0, 9997.050280223386, 2525.7095434025136, 2550.41340491687, 2564.2563656539583, 2572.0354866924904, 2573.4209802794408, 2454.447749256421, 2581.070814506776, 2581.4966081614093, 2586.844789312647, 2603.9349016274555, 2502.6621406110216, 2534.0, 2571.6656834611567, 2576.8325058871205, 2584.3797488602695, 2513.258588716324, 2515.333436639292, 2517.0561039610147, 2552.0, 2558.3731985663444, 2469.876506174702, 2486.17956074495, 2487.3905893056135, 2501.312139161727, 2518.26970178648, 9881.505924703808, 9900.702978910644, 10028.348157369748, 10030.297879148462, 10081.193504519693, 5479.315085614298, 5577.3028371453565, 5591.189277554772, 5598.720063996784, 5600.719964001783, 3666.431703086026, 3741.5136032315895, 3774.5093137892172, 3819.9045023874623, 3877.3990031545154, 1199.3343694249688, 1228.2139430764319, 1254.5859866244118, 1264.2667252993242, 1269.0608949377486, 14217.85782142186, 14256.857431425657, 14292.85707142936, 14316.498922537661, 14336.42654293827, 5064.645474816727, 5171.7672704728375, 5255.842324730264, 5257.842264732064, 5292.0, 11850.407479625985, 11860.169788114921, 11939.044876409866, 11959.940200298943, 12003.459844307026, 5911.438413350725, 5914.497267732291, 5917.437843404869, 5925.525957923356, 5928.940710592925, 4733.313669517901, 5172.870678233075, 5322.787088516416, 5373.435789242073, 5435.972820135953, 4397.538258482878, 4438.622717069085, 4479.843205487792, 4518.0, 4594.264917613166, 1360.3810266328796, 1376.3462355381314, 1383.1493631416606, 1387.8681525255085, 1404.1504889541884, 1018.5365658625308, 1078.719532921446, 1079.7516571188514, 1083.8590983172137, 1091.6070214722693, 6570.342965703391, 6653.534252602368, 6739.663016849138, 6766.086578311963, 6848.8972665409065, 483.1279540429558, 485.39083450269686, 489.86283840524, 491.0792264504055, 496.85591178557814, 4543.636509079266, 4604.1252162089095, 4667.556582124693, 4792.0, 4844.176489996709, 12036.45835937385, 12224.938875305566, 12344.75310493785, 12345.567905123457, 12398.75202495945, 11480.425978701032, 11981.88018119825, 12014.699632509257, 12440.81338779919, 12511.186772859735, 9019.77450563728, 9143.634254629873, 9150.496722680316, 9158.404703694372, 9251.583678734338, 6373.2989371168205, 6627.1716035495565, 6628.90056649141, 6645.368689974444, 6668.933310667026, 11314.60398886068, 11345.886541134483, 11359.432028398382, 11404.714882128013, 11454.427278635869, 11249.1563132767, 11325.490352934303, 11529.0, 11622.302661840316, 11676.474558644712, 7443.069616297963, 8499.0, 8799.780005499913, 8836.91163088361, 8893.599788009426, 9162.816743665222, 9388.812223755624, 9456.432614042904, 9479.146876781122, 9506.429614223169, 8214.301784348158, 8426.873596895928, 8448.831023379375, 8497.405181637474, 8707.521086340312, 7984.720534781255, 8009.759707208793, 8361.0, 8431.988161420424, 8463.534505602005, 7473.140588832136, 7787.5716835574585, 7820.648070836713, 7842.529448233125, 7861.371090312876, 5613.793034497603, 5695.373508913934, 5715.628484148517, 5761.222234998221, 5794.797182098607, 5820.447057529528, 5881.441263080001, 5948.970255148696, 5953.672548009901, 6063.939360606338], "value": 5054.9698587715575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.8957218048496, 361.6130740108085, 362.21760996248094, 365.84268764431283, 367.95952445231035, 5673.574481913863, 5772.3361813391475, 5779.479846813786, 5819.301683797945, 5857.326407463148, 5757.798477053304, 5784.363719990798, 5795.884082318362, 5804.071348584227, 5821.56338274629, 4588.17412865684, 4591.7244965302025, 4606.723596584207, 4621.2837010263465, 4636.744979026153, 4480.6191473724775, 4580.274736531758, 4584.587387135157, 4595.908081838361, 4622.560856718623, 4524.185646583614, 4538.954610453886, 4545.772641386823, 4559.088182363524, 4567.109413664337, 4561.156186105564, 4562.4753153387455, 4582.747948862844, 4586.220342541776, 4609.608183304423, 3884.038560526944, 3942.073612701013, 3945.3884647879627, 3953.743006704555, 3972.8013599320207, 5611.691356975365, 5617.578681598856, 5650.943490565124, 5653.551333686448, 5660.264165658438, 5091.210862316347, 5153.149730294497, 5179.14544100223, 5181.766820493049, 5193.922091168635, 6038.30559485656, 6043.335233124397, 6048.455638992467, 6075.574709770318, 6079.233948553442, 4351.6518678505645, 4353.238183317939, 4353.303471444554, 4363.716358436692, 4370.0, 6095.725692343811, 6285.371462853678, 6337.397947195009, 6339.492840572743, 6362.713677884506, 4466.129104824593, 4466.798994045211, 4470.642348612103, 4478.57453541913, 4486.820527178941, 4513.571210734975, 4521.186186486464, 4553.317002449659, 4554.497015984717, 4589.954100458953, 4956.306117143603, 5138.58891288696, 5217.765200565984, 5221.598064890117, 5233.188855727369, 5979.402059793986, 6065.636061836304, 6132.386761323921, 6148.538859585549, 6180.258368995749, 1535.7696345548034, 1577.5188567487028, 1578.329210085709, 1582.114016150959, 1584.1683116364002, 969.8011907559074, 985.669800616787, 1000.7940431779833, 1006.3646574316735, 1025.179856115105, 5179.766910489037, 5313.483538991826, 5315.787368505294, 5321.190795129674, 5370.610078515023, 5521.834344969658, 5557.3886872444, 5571.498565129158, 5577.247071645357, 5612.492192379763, 5017.365882911501, 5281.709505977208, 5289.894202115936, 5360.892782144335, 5381.919271210935, 2940.867660955262, 2950.7196816302417, 2968.6437627484406, 3005.879764809427, 3006.406604499619, 5666.413404246818, 5678.290213723285, 5708.65748055118, 5754.539636829044, 5776.353739184312, 5984.371640977722, 6375.171227740364, 6442.259140198843, 6451.161349024596, 6474.352564743488, 6505.447037001908, 6521.510886683518, 6545.901811472737, 6561.015847622894, 6574.605523668595, 6081.27024757032, 6286.8428289291305, 6298.464630506275, 6306.400891915261, 6321.620702757849, 6225.782097626561, 6239.532035097295, 6256.061590761422, 6263.248410190807, 6272.8118156455375, 6361.363863613787, 6464.864540697852, 6506.934930650633, 6524.934750652433, 6542.738290468328, 4487.61855242295, 4490.348899409632, 4514.548545145591, 4532.410786597721, 4536.705114167568, 5990.46085852258, 6202.255729312511, 6280.560360774702, 6319.936800632118, 6382.712777924911, 6088.36072212429, 6153.600015998945, 6157.475435071599, 6182.876342473035, 6192.81421557354, 6029.57792954489, 6110.541709371726, 6113.266408031065, 6140.785072522619, 6177.598456100339, 6076.848078798065, 6119.19483752303, 6123.9693801532485, 6141.969290153521, 6147.661878596541, 6127.84680382994, 6154.569180157346, 6191.018873960289, 6194.0, 6204.162438070803, 5855.619384739978, 6031.336552979081, 6045.75816967334, 6051.334353221054, 6070.514358851193], "value": 4591.532375915838, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.6692655231698, 780.009388077142, 858.2704701004147, 865.3077537969631, 883.8011447424328, 16198.514044578667, 16434.34262629495, 16664.83335166648, 16666.58333541662, 17847.375341863037, 17744.82255177448, 17893.28426862924, 18063.819361806378, 18070.819291807078, 18071.186796594153, 860.9182127697859, 888.138505649519, 902.9458232506071, 913.808100298938, 914.7941713114529, 886.2334081019903, 892.5492626223748, 894.114826321943, 900.4822227219342, 901.1484147480634, 941.9434833909953, 946.153192892361, 946.7822400847809, 954.5942974235957, 954.7994921066585, 938.8028514012066, 941.0542404883083, 941.1200527506769, 943.1747221181466, 944.338962726091, 692.069166970424, 692.8475735338218, 693.7641201991297, 694.4964900447186, 695.7008486350918, 15370.077795332205, 15547.689046219013, 15604.219789010504, 15635.0, 15711.292991815395, 15399.923000384926, 15488.612784680361, 15615.31381377637, 16124.919375403166, 16412.343506259735, 13063.729628268713, 13457.192568445924, 13490.730185396334, 13608.455661773518, 13746.450141994308, 4094.9385759213637, 4145.937810932838, 4155.729877557949, 4179.0, 4258.105797782469, 93081.5345923266, 94924.0, 95308.52345738227, 96017.51991239999, 98050.0, 12591.874081259253, 12628.936855315664, 12686.619401417973, 12727.554535591395, 12762.10665253442, 22204.555908882056, 22331.330060098222, 22367.44081397978, 22394.32817015492, 22396.776032239795, 9178.128077832594, 9191.126842949909, 9244.260459163384, 9268.582913768896, 9381.484018378918, 5247.501487358695, 5430.456954304538, 5446.3191253998375, 5449.482299181572, 5449.618526703092, 4066.1461093170174, 4215.178040282116, 4218.599233072853, 4219.156168766259, 4219.493660760667, 685.2496516314627, 689.1592257445908, 697.1494776372817, 702.220535205921, 713.6217804563589, 753.2806170107534, 790.6323559544725, 800.7077416742846, 810.2383759266271, 818.8444195602935, 10009.649662261785, 10878.347299162075, 11274.379909105079, 11345.149113816497, 11465.885341146648, 803.4255507312183, 803.8794180872916, 824.3034635732796, 828.159418190537, 828.5484410996022, 5402.756875940592, 5525.751341189576, 5645.633033852787, 5660.915086273709, 5705.144228365778, 135919.32040339735, 136057.0, 136278.31860840632, 136474.31762841123, 138691.0, 71711.6414417935, 93300.53349733209, 118844.02889927648, 118887.0, 121201.39399302947, 93688.0, 94980.05019949851, 97031.0, 97175.02824971802, 97692.0, 10879.0, 10900.776772699275, 11292.15308851823, 11311.604093856675, 11378.0, 54629.0, 54812.451875480736, 55753.0, 56882.7155864218, 57130.71434642967, 77152.22847771675, 83584.0, 84234.15765842509, 85399.0, 87422.56288718515, 13637.931810340884, 13681.521146759816, 13754.243516606683, 14002.089864158797, 14062.507812226522, 15346.232688365313, 15364.615884602974, 15490.767638485206, 16028.0, 16490.83509164941, 13927.0, 14048.508302209373, 14768.77846832277, 15217.086974781549, 15418.845811541742, 13776.448942042607, 13935.790963135763, 14025.158490490603, 14070.155790652594, 14669.92665036668, 14244.786328205293, 14610.0, 14892.851071489147, 15129.39482420691, 15274.923625381802, 11548.076153907836, 11683.123765717435, 11906.166568340133, 11939.880601194225, 12034.398280086136, 12075.51697932073, 12250.387480626112, 12518.186317889307, 12649.810252846399, 12711.80932285998], "value": 8404.731900314173, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1497.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 105.0, 110.0, 111.0, 110.0, 108.0, 109.0, 111.0, 111.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 120.0, 144.0, 171.0, 116.0, 136.0, 140.0, 117.0, 173.0, 116.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 162.0, 161.0, 161.0, 162.0, 160.0, 161.0, 160.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 180.0, 75.0, 75.0, 75.0, 76.0, 190.0, 76.0], "value": 97.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 146.0, 83.0, 87.0, 83.0, 140.0, 83.0, 82.0, 151.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 146.0, 147.0, 146.0, 146.0, 147.0, 149.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 95.0, 95.0, 98.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 53.0, 52.0, 51.0, 51.0, 51.0, 53.0, 50.0, 51.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 59.0, 58.0, 59.0, 59.0, 58.0, 59.0, 61.0, 60.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 73.0, 72.0, 82.0, 71.0, 71.0, 76.0, 72.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 131.0, 121.0, 118.0, 117.0, 131.0, 117.0, 123.0, 159.0, 125.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 154.0, 153.0, 152.0, 161.0, 154.0, 156.0, 153.0, 155.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 60.0, 61.0, 62.0, 62.0, 62.0, 64.0, 65.0], "value": 62.5, "unit": "score"}]}]} 07:20:18 INFO - Return code: 0 07:20:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:18 ERROR - # TBPL SUCCESS # 07:20:18 INFO - Running post-action listener: _resource_record_post_action 07:20:18 INFO - [mozharness: 2016-09-01 14:20:18.321799Z] Finished run-tests step (success) 07:20:18 INFO - Running post-run listener: _resource_record_post_run 07:20:18 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 270336; Write bytes: 328085504; Read time: 40; Write time: 209580 07:20:18 INFO - TinderboxPrint: CPU usage
13.2% 07:20:18 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 40 07:20:18 INFO - TinderboxPrint: I/O write bytes / time
328,085,504 / 209,580 07:20:18 INFO - TinderboxPrint: CPU idle
8,228.0 (86.7%) 07:20:18 INFO - TinderboxPrint: CPU user
1,199.1 (12.6%) 07:20:18 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79642624; Read time: 0; Write time: 68364 07:20:18 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 247930880; Read time: 28; Write time: 140976 07:20:18 INFO - Running post-run listener: _upload_blobber_files 07:20:18 INFO - Blob upload gear active. 07:20:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:18 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 07:20:18 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:20:18 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:20:19 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:20:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:21 INFO - (blobuploader) - INFO - Done attempting. 07:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:28 INFO - (blobuploader) - INFO - Done attempting. 07:20:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:20:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:20:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:29 INFO - (blobuploader) - INFO - Done attempting. 07:20:29 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:29 INFO - Return code: 0 07:20:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96bd879c91ff7db4efd132936f29b73713d19d25cf4f78c23138008de9e20b59c067d9a515e8852428429d8058c0c6ebd8303cf0df7b5c9f20a10b5d04c3761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1db509ed67066f2f2b6f386b017f08b4136030d9f979bf35858a2b271c74a504596049a9432c9e969a211356f2f4117932074836cdc1125c41080db315eb1a0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384977c084b93970228315fa35edbdd873a2d0f64337b73d3d35a61e9d2bb1aad7e5d4df3299c88e0d15d543c581bcb9523ce14c0263d08af9703e8b7568c565"} 07:20:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:29 INFO - Contents: 07:20:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96bd879c91ff7db4efd132936f29b73713d19d25cf4f78c23138008de9e20b59c067d9a515e8852428429d8058c0c6ebd8303cf0df7b5c9f20a10b5d04c3761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1db509ed67066f2f2b6f386b017f08b4136030d9f979bf35858a2b271c74a504596049a9432c9e969a211356f2f4117932074836cdc1125c41080db315eb1a0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384977c084b93970228315fa35edbdd873a2d0f64337b73d3d35a61e9d2bb1aad7e5d4df3299c88e0d15d543c581bcb9523ce14c0263d08af9703e8b7568c565"} 07:20:29 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:29 INFO - Copying logs to upload dir... 07:20:29 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1682.242529 ========= master_lag: 3.28 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 5 secs) (at 2016-09-01 07:20:32.517030) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-01 07:20:32.520051) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96bd879c91ff7db4efd132936f29b73713d19d25cf4f78c23138008de9e20b59c067d9a515e8852428429d8058c0c6ebd8303cf0df7b5c9f20a10b5d04c3761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1db509ed67066f2f2b6f386b017f08b4136030d9f979bf35858a2b271c74a504596049a9432c9e969a211356f2f4117932074836cdc1125c41080db315eb1a0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384977c084b93970228315fa35edbdd873a2d0f64337b73d3d35a61e9d2bb1aad7e5d4df3299c88e0d15d543c581bcb9523ce14c0263d08af9703e8b7568c565"} build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96bd879c91ff7db4efd132936f29b73713d19d25cf4f78c23138008de9e20b59c067d9a515e8852428429d8058c0c6ebd8303cf0df7b5c9f20a10b5d04c3761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1db509ed67066f2f2b6f386b017f08b4136030d9f979bf35858a2b271c74a504596049a9432c9e969a211356f2f4117932074836cdc1125c41080db315eb1a0d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384977c084b93970228315fa35edbdd873a2d0f64337b73d3d35a61e9d2bb1aad7e5d4df3299c88e0d15d543c581bcb9523ce14c0263d08af9703e8b7568c565"}' ========= master_lag: 1.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-01 07:20:33.590794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:33.591218) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472735053.678918-562923487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 2.60 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:36.192697) ========= ========= Started reboot skipped (results: 3, elapsed: 5 secs) (at 2016-09-01 07:20:36.193187) ========= ========= Finished reboot skipped (results: 3, elapsed: 5 secs) (at 2016-09-01 07:20:41.607094) ========= ========= Total master_lag: 7.07 =========