builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1472232608.33 results: success (0) buildid: 20160826071918 builduid: 448ff2d3361c47ccacbcc3af04dfe36f revision: e58e63e362ff2a402bc7f22f0193097d07c418f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.329682) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.330496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.331019) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.347312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.347890) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 10:30:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 55.0K=0.2s 2016-08-26 10:30:08 (55.0 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.336892 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.703826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.704184) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019093 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.754037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:08.754442) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e58e63e362ff2a402bc7f22f0193097d07c418f1 --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 e58e63e362ff2a402bc7f22f0193097d07c418f1 --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=9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 10:30:08,973 truncating revision to first 12 chars 2016-08-26 10:30:08,973 Setting DEBUG logging. 2016-08-26 10:30:08,973 attempt 1/10 2016-08-26 10:30:08,973 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e58e63e362ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 10:30:09,613 unpacking tar archive at: mozilla-aurora-e58e63e362ff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.916489 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:09.685208) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:09.685581) ========= script_repo_revision: e58e63e362ff2a402bc7f22f0193097d07c418f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:09.686030) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:09.686469) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 10:30:09.696275) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 3 secs) (at 2016-08-26 10:30:09.696721) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:30:10 INFO - MultiFileLogger online at 20160826 10:30:10 in /builds/slave/test 10:30:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:30:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:30:10 INFO - {'append_to_log': False, 10:30:10 INFO - 'base_work_dir': '/builds/slave/test', 10:30:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:30:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:30:10 INFO - 'branch': 'Mozilla-Aurora', 10:30:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:30:10 INFO - 'config_files': ('talos/linux_config.py',), 10:30:10 INFO - 'default_actions': ('clobber', 10:30:10 INFO - 'read-buildbot-config', 10:30:10 INFO - 'download-and-extract', 10:30:10 INFO - 'populate-webroot', 10:30:10 INFO - 'create-virtualenv', 10:30:10 INFO - 'install', 10:30:10 INFO - 'run-tests'), 10:30:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:30:10 INFO - 'download_minidump_stackwalk': True, 10:30:10 INFO - 'download_symbols': 'ondemand', 10:30:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:30:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:30:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:30:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:30:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:30:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:30:10 INFO - 'installer_path': 'installer.exe', 10:30:10 INFO - 'log_level': 'info', 10:30:10 INFO - 'log_name': 'talos', 10:30:10 INFO - 'log_to_console': True, 10:30:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:30:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:30:10 INFO - 'opt_config_files': (), 10:30:10 INFO - 'pip_index': False, 10:30:10 INFO - 'sps_profile': False, 10:30:10 INFO - 'sps_profile_interval': 0, 10:30:10 INFO - 'suite': 'dromaeojs', 10:30:10 INFO - 'system_bits': '32', 10:30:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:30:10 INFO - 'title': 'talos-linux64-ix-013', 10:30:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:30:10 INFO - 'use_talos_json': True, 10:30:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:30:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:30:10 INFO - 'work_dir': 'build'} 10:30:10 INFO - [mozharness: 2016-08-26 17:30:10.009569Z] Running clobber step. 10:30:10 INFO - Running pre-action listener: _resource_record_pre_action 10:30:10 INFO - Running main action method: clobber 10:30:10 INFO - rmtree: /builds/slave/test/build 10:30:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:30:10 INFO - Running post-action listener: _resource_record_post_action 10:30:10 INFO - [mozharness: 2016-08-26 17:30:10.453840Z] Finished clobber step (success) 10:30:10 INFO - [mozharness: 2016-08-26 17:30:10.453941Z] Running read-buildbot-config step. 10:30:10 INFO - Running pre-action listener: _resource_record_pre_action 10:30:10 INFO - Running main action method: read_buildbot_config 10:30:10 INFO - Using buildbot properties: 10:30:10 INFO - { 10:30:10 INFO - "project": "", 10:30:10 INFO - "product": "firefox", 10:30:10 INFO - "script_repo_revision": "production", 10:30:10 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 10:30:10 INFO - "repository": "", 10:30:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 10:30:10 INFO - "buildid": "20160826071918", 10:30:10 INFO - "pgo_build": "True", 10:30:10 INFO - "basedir": "/builds/slave/test", 10:30:10 INFO - "buildnumber": 702, 10:30:10 INFO - "slavename": "talos-linux64-ix-013", 10:30:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:30:10 INFO - "platform": "ubuntu64_hw", 10:30:10 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 10:30:10 INFO - "branch": "mozilla-aurora", 10:30:10 INFO - "repo_path": "releases/mozilla-aurora", 10:30:10 INFO - "revision": "e58e63e362ff2a402bc7f22f0193097d07c418f1", 10:30:10 INFO - "stage_platform": "linux64-pgo", 10:30:10 INFO - "builduid": "448ff2d3361c47ccacbcc3af04dfe36f", 10:30:10 INFO - "slavebuilddir": "test" 10:30:10 INFO - } 10:30:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 10:30:10 INFO - Running post-action listener: _resource_record_post_action 10:30:10 INFO - [mozharness: 2016-08-26 17:30:10.456403Z] Finished read-buildbot-config step (success) 10:30:10 INFO - [mozharness: 2016-08-26 17:30:10.456493Z] Running download-and-extract step. 10:30:10 INFO - Running pre-action listener: _resource_record_pre_action 10:30:10 INFO - Running main action method: download_and_extract 10:30:10 INFO - mkdir: /builds/slave/test/build/tests 10:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:30:10 INFO - https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:30:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:30:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:30:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:30:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:30:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:30:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:30:10 INFO - retry: Failed, sleeping 30 seconds before retrying 10:30:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:30:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:30:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:30:40 INFO - retry: Failed, sleeping 60 seconds before retrying 10:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:31:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:31:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:31:40 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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! 10:31:40 INFO - Caught exception: HTTP Error 404: Not Found 10:31:40 INFO - Caught exception: HTTP Error 404: Not Found 10:31:40 INFO - Caught exception: HTTP Error 404: Not Found 10:31:40 INFO - trying https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:31:40 INFO - Downloading https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:31:41 INFO - Downloaded 1361 bytes. 10:31:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:31:41 INFO - Using the following test package requirements: 10:31:41 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 10:31:41 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 10:31:41 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 10:31:41 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'jsshell-linux-x86_64.zip'], 10:31:41 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 10:31:41 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 10:31:41 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 10:31:41 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 10:31:41 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 10:31:41 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:31:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 10:31:41 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:31:41 INFO - https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:31:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:31:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:31:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:31:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:31:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:31:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:31:41 INFO - retry: Failed, sleeping 30 seconds before retrying 10:32:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:32:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:32:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:32:11 INFO - retry: Failed, sleeping 60 seconds before retrying 10:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:33:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:33:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:33:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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! 10:33:11 INFO - Caught exception: HTTP Error 404: Not Found 10:33:11 INFO - Caught exception: HTTP Error 404: Not Found 10:33:11 INFO - Caught exception: HTTP Error 404: Not Found 10:33:11 INFO - trying https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:33:11 INFO - Downloading https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:33:12 INFO - Downloaded 21768884 bytes. 10:33:12 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'] 10:33:12 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 10:33:13 INFO - Return code: 0 10:33:13 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 10:33:13 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')]} 10:33:13 INFO - https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:33:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:33:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:33:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:33:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:33:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:33:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:33:13 INFO - retry: Failed, sleeping 30 seconds before retrying 10:33:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:33:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:33:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:33:43 INFO - retry: Failed, sleeping 60 seconds before retrying 10:34:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:34:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:34:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:34:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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! 10:34:43 INFO - Caught exception: HTTP Error 404: Not Found 10:34:43 INFO - Caught exception: HTTP Error 404: Not Found 10:34:43 INFO - Caught exception: HTTP Error 404: Not Found 10:34:43 INFO - trying https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:34:43 INFO - Downloading https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:34:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:34:44 INFO - Downloaded 21768884 bytes. 10:34:44 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'] 10:34:44 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 10:34:45 INFO - Return code: 0 10:34: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')]} 10:34:45 INFO - https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:34:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:34:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:34:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:34:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:34:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:34:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:34:45 INFO - retry: Failed, sleeping 30 seconds before retrying 10:35:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:35:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:35:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:35:15 INFO - retry: Failed, sleeping 60 seconds before retrying 10:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:36:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:36:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:36:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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! 10:36:15 INFO - Caught exception: HTTP Error 404: Not Found 10:36:15 INFO - Caught exception: HTTP Error 404: Not Found 10:36:15 INFO - Caught exception: HTTP Error 404: Not Found 10:36:15 INFO - trying https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:36:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:36:16 INFO - Downloaded 14413723 bytes. 10:36:16 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'] 10:36:16 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 10:36:16 INFO - Return code: 0 10:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:36:16 INFO - https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:36:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:36:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:36:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:36:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:36:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:36:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:36:16 INFO - retry: Failed, sleeping 30 seconds before retrying 10:36:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:36:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:36:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:36:47 INFO - retry: Failed, sleeping 60 seconds before retrying 10:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:37:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:37:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:37:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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! 10:37:47 INFO - Caught exception: HTTP Error 404: Not Found 10:37:47 INFO - Caught exception: HTTP Error 404: Not Found 10:37:47 INFO - Caught exception: HTTP Error 404: Not Found 10:37:47 INFO - trying https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:37:47 INFO - Downloading https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/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 10:37:48 INFO - Downloaded 63755167 bytes. 10:37:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:37:48 INFO - mkdir: /builds/slave/test/properties 10:37:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:37:48 INFO - Writing to file /builds/slave/test/properties/build_url 10:37:48 INFO - Contents: 10:37:48 INFO - build_url:https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:37:49 INFO - Running post-action listener: _resource_record_post_action 10:37:49 INFO - Running post-action listener: set_extra_try_arguments 10:37:49 INFO - [mozharness: 2016-08-26 17:37:49.132628Z] Finished download-and-extract step (success) 10:37:49 INFO - [mozharness: 2016-08-26 17:37:49.132861Z] Running populate-webroot step. 10:37:49 INFO - Running pre-action listener: _resource_record_pre_action 10:37:49 INFO - Running main action method: populate_webroot 10:37:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:37:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:37:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:37:49 INFO - '--tppagecycles', 10:37:49 INFO - '7'], 10:37:49 INFO - 'tests': ['tsvgm']}}, 10:37:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:37:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:37:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:37:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:37:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:37:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:37:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['damp', 'tps']}, 10:37:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'tests': ['damp', 'tps']}, 10:37:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['dromaeo_dom']}, 10:37:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:37:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['basic_compositor_video']}, 10:37:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:37:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['a11yr', 10:37:49 INFO - 'ts_paint', 10:37:49 INFO - 'tpaint', 10:37:49 INFO - 'sessionrestore', 10:37:49 INFO - 'sessionrestore_no_auto_restore', 10:37:49 INFO - 'tabpaint']}, 10:37:49 INFO - 'other-e10s': {'tests': ['a11yr', 10:37:49 INFO - 'ts_paint', 10:37:49 INFO - 'tpaint', 10:37:49 INFO - 'sessionrestore', 10:37:49 INFO - 'sessionrestore_no_auto_restore', 10:37:49 INFO - 'tabpaint']}, 10:37:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:37:49 INFO - 'ts_paint', 10:37:49 INFO - 'tpaint', 10:37:49 INFO - 'sessionrestore', 10:37:49 INFO - 'sessionrestore_no_auto_restore', 10:37:49 INFO - 'tabpaint']}, 10:37:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:37:49 INFO - 'ts_paint', 10:37:49 INFO - 'tpaint', 10:37:49 INFO - 'sessionrestore', 10:37:49 INFO - 'sessionrestore_no_auto_restore', 10:37:49 INFO - 'tabpaint']}, 10:37:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['a11yr', 10:37:49 INFO - 'ts_paint', 10:37:49 INFO - 'tpaint', 10:37:49 INFO - 'sessionrestore', 10:37:49 INFO - 'sessionrestore_no_auto_restore', 10:37:49 INFO - 'tabpaint']}, 10:37:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['a11yr', 10:37:49 INFO - 'ts_paint', 10:37:49 INFO - 'tpaint', 10:37:49 INFO - 'sessionrestore', 10:37:49 INFO - 'sessionrestore_no_auto_restore', 10:37:49 INFO - 'tabpaint']}, 10:37:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['tsvgx', 10:37:49 INFO - 'tsvgr_opacity', 10:37:49 INFO - 'tart', 10:37:49 INFO - 'tscrollx', 10:37:49 INFO - 'cart']}, 10:37:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:37:49 INFO - 'tsvgr_opacity', 10:37:49 INFO - 'tart', 10:37:49 INFO - 'tscrollx', 10:37:49 INFO - 'cart']}, 10:37:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'talos_options': ['--disable-e10s'], 10:37:49 INFO - 'tests': ['tp5o']}, 10:37:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'tests': ['tp5o']}, 10:37:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'talos_options': ['--disable-e10s', 10:37:49 INFO - '--xperf_path', 10:37:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:37:49 INFO - 'tests': ['tp5n']}, 10:37:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:49 INFO - 'talos_options': ['--xperf_path', 10:37:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:37:49 INFO - 'tests': ['tp5n']}}, 10:37:49 INFO - 'talos.zip': {'path': '', 10:37:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:37:49 INFO - Running post-action listener: _resource_record_post_action 10:37:49 INFO - [mozharness: 2016-08-26 17:37:49.149175Z] Finished populate-webroot step (success) 10:37:49 INFO - [mozharness: 2016-08-26 17:37:49.149304Z] Running create-virtualenv step. 10:37:49 INFO - Running pre-action listener: _resource_record_pre_action 10:37:49 INFO - Running main action method: create_virtualenv 10:37:49 INFO - Creating virtualenv /builds/slave/test/build/venv 10:37:49 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 10:37:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:37:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:37:49 INFO - Using real prefix '/usr' 10:37:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:37:49 INFO - Installing distribute.............................................................................................................................................................................................done. 10:37:51 INFO - Installing pip.................done. 10:37:51 INFO - Return code: 0 10:37:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:37:51 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')]} 10:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:51 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')]} 10:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:51 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:37:51 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 10:37:51 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 10:37:51 INFO - Using env: {'DISPLAY': ':0', 10:37:51 INFO - 'HOME': '/home/cltbld', 10:37:51 INFO - 'LANG': 'en_US.UTF-8', 10:37:51 INFO - 'LANGUAGE': 'en_US:en', 10:37:51 INFO - 'LOGNAME': 'cltbld', 10:37:51 INFO - 'MAIL': '/var/mail/cltbld', 10:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:51 INFO - 'MOZ_NO_REMOTE': '1', 10:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:51 INFO - 'NO_EM_RESTART': '1', 10:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:51 INFO - 'PWD': '/builds/slave/test', 10:37:51 INFO - 'SHELL': '/bin/bash', 10:37:51 INFO - 'SHLVL': '1', 10:37:51 INFO - 'TERM': 'linux', 10:37:51 INFO - 'TMOUT': '86400', 10:37:51 INFO - 'USER': 'cltbld', 10:37:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:51 INFO - '_': '/tools/buildbot/bin/python'} 10:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:51 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 10:37:51 INFO - Cleaning up... 10:37:51 INFO - Return code: 0 10:37:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:37:51 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')]} 10:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:51 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')]} 10:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:51 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:37:51 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 10:37:51 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 10:37:51 INFO - Using env: {'DISPLAY': ':0', 10:37:51 INFO - 'HOME': '/home/cltbld', 10:37:51 INFO - 'LANG': 'en_US.UTF-8', 10:37:51 INFO - 'LANGUAGE': 'en_US:en', 10:37:51 INFO - 'LOGNAME': 'cltbld', 10:37:51 INFO - 'MAIL': '/var/mail/cltbld', 10:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:51 INFO - 'MOZ_NO_REMOTE': '1', 10:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:51 INFO - 'NO_EM_RESTART': '1', 10:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:51 INFO - 'PWD': '/builds/slave/test', 10:37:51 INFO - 'SHELL': '/bin/bash', 10:37:51 INFO - 'SHLVL': '1', 10:37:51 INFO - 'TERM': 'linux', 10:37:51 INFO - 'TMOUT': '86400', 10:37:51 INFO - 'USER': 'cltbld', 10:37:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:51 INFO - '_': '/tools/buildbot/bin/python'} 10:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:52 INFO - Downloading/unpacking psutil>=3.1.1 10:37:52 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 10:37:52 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 10:37:52 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 10:37:52 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 10:37:52 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 10:37:52 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 10:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:37:54 INFO - Installing collected packages: psutil 10:37:54 INFO - Running setup.py install for psutil 10:37:54 INFO - building 'psutil._psutil_linux' extension 10:37:54 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 10:37:54 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 10:37:54 INFO - building 'psutil._psutil_posix' extension 10:37:54 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 10:37:54 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 10:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:37:54 INFO - Successfully installed psutil 10:37:54 INFO - Cleaning up... 10:37:54 INFO - Return code: 0 10:37:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:54 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:37:54 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 10:37:54 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 10:37:54 INFO - Using env: {'DISPLAY': ':0', 10:37:54 INFO - 'HOME': '/home/cltbld', 10:37:54 INFO - 'LANG': 'en_US.UTF-8', 10:37:54 INFO - 'LANGUAGE': 'en_US:en', 10:37:54 INFO - 'LOGNAME': 'cltbld', 10:37:54 INFO - 'MAIL': '/var/mail/cltbld', 10:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:54 INFO - 'MOZ_NO_REMOTE': '1', 10:37:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:54 INFO - 'NO_EM_RESTART': '1', 10:37:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:54 INFO - 'PWD': '/builds/slave/test', 10:37:54 INFO - 'SHELL': '/bin/bash', 10:37:54 INFO - 'SHLVL': '1', 10:37:54 INFO - 'TERM': 'linux', 10:37:54 INFO - 'TMOUT': '86400', 10:37:54 INFO - 'USER': 'cltbld', 10:37:54 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:54 INFO - '_': '/tools/buildbot/bin/python'} 10:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:37:54 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 10:37:54 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 10:37:54 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 10:37:54 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 10:37:54 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 10:37:54 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 10:37:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:37:56 INFO - Installing collected packages: mozsystemmonitor 10:37:56 INFO - Running setup.py install for mozsystemmonitor 10:37:56 INFO - Successfully installed mozsystemmonitor 10:37:56 INFO - Cleaning up... 10:37:57 INFO - Return code: 0 10:37:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:57 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:37:57 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 10:37:57 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 10:37:57 INFO - Using env: {'DISPLAY': ':0', 10:37:57 INFO - 'HOME': '/home/cltbld', 10:37:57 INFO - 'LANG': 'en_US.UTF-8', 10:37:57 INFO - 'LANGUAGE': 'en_US:en', 10:37:57 INFO - 'LOGNAME': 'cltbld', 10:37:57 INFO - 'MAIL': '/var/mail/cltbld', 10:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:57 INFO - 'MOZ_NO_REMOTE': '1', 10:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:57 INFO - 'NO_EM_RESTART': '1', 10:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:57 INFO - 'PWD': '/builds/slave/test', 10:37:57 INFO - 'SHELL': '/bin/bash', 10:37:57 INFO - 'SHLVL': '1', 10:37:57 INFO - 'TERM': 'linux', 10:37:57 INFO - 'TMOUT': '86400', 10:37:57 INFO - 'USER': 'cltbld', 10:37:57 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:57 INFO - '_': '/tools/buildbot/bin/python'} 10:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:57 INFO - Downloading/unpacking jsonschema==2.5.1 10:37:57 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 10:37:57 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 10:37:57 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 10:37:57 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 10:37:57 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 10:37:57 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 10:37:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:37:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:37:59 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 10:37:59 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 10:37:59 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 10:37:59 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 10:37:59 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 10:37:59 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 10:37:59 INFO - Downloading functools32-3.2.3-2.tar.gz 10:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:37:59 INFO - warning: no files found matching '*.txt' 10:37:59 INFO - no previously-included directories found matching 'build' 10:37:59 INFO - no previously-included directories found matching 'dist' 10:37:59 INFO - no previously-included directories found matching '.git*' 10:37:59 INFO - Installing collected packages: jsonschema, functools32 10:37:59 INFO - Running setup.py install for functools32 10:37:59 INFO - warning: no files found matching '*.txt' 10:37:59 INFO - no previously-included directories found matching 'build' 10:37:59 INFO - no previously-included directories found matching 'dist' 10:37:59 INFO - no previously-included directories found matching '.git*' 10:37:59 INFO - Successfully installed jsonschema functools32 10:37:59 INFO - Cleaning up... 10:37:59 INFO - Return code: 0 10:37:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:37:59 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')]} 10:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:59 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')]} 10:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:59 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:37:59 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 10:37:59 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 10:37:59 INFO - Using env: {'DISPLAY': ':0', 10:37:59 INFO - 'HOME': '/home/cltbld', 10:37:59 INFO - 'LANG': 'en_US.UTF-8', 10:37:59 INFO - 'LANGUAGE': 'en_US:en', 10:37:59 INFO - 'LOGNAME': 'cltbld', 10:37:59 INFO - 'MAIL': '/var/mail/cltbld', 10:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:59 INFO - 'MOZ_NO_REMOTE': '1', 10:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:59 INFO - 'NO_EM_RESTART': '1', 10:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:59 INFO - 'PWD': '/builds/slave/test', 10:37:59 INFO - 'SHELL': '/bin/bash', 10:37:59 INFO - 'SHLVL': '1', 10:37:59 INFO - 'TERM': 'linux', 10:37:59 INFO - 'TMOUT': '86400', 10:37:59 INFO - 'USER': 'cltbld', 10:37:59 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:59 INFO - '_': '/tools/buildbot/bin/python'} 10:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:37:59 INFO - Cleaning up... 10:37:59 INFO - Return code: 0 10:37:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:37:59 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')]} 10:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:59 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')]} 10:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:59 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:37:59 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 10:37:59 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 10:37:59 INFO - Using env: {'DISPLAY': ':0', 10:37:59 INFO - 'HOME': '/home/cltbld', 10:37:59 INFO - 'LANG': 'en_US.UTF-8', 10:37:59 INFO - 'LANGUAGE': 'en_US:en', 10:37:59 INFO - 'LOGNAME': 'cltbld', 10:37:59 INFO - 'MAIL': '/var/mail/cltbld', 10:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:59 INFO - 'MOZ_NO_REMOTE': '1', 10:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:59 INFO - 'NO_EM_RESTART': '1', 10:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:59 INFO - 'PWD': '/builds/slave/test', 10:37:59 INFO - 'SHELL': '/bin/bash', 10:37:59 INFO - 'SHLVL': '1', 10:37:59 INFO - 'TERM': 'linux', 10:37:59 INFO - 'TMOUT': '86400', 10:37:59 INFO - 'USER': 'cltbld', 10:37:59 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:59 INFO - '_': '/tools/buildbot/bin/python'} 10:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:00 INFO - Downloading/unpacking blobuploader==1.2.4 10:38:00 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 10:38:00 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 10:38:00 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 10:38:00 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 10:38:00 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 10:38:00 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 10:38:01 INFO - Downloading blobuploader-1.2.4.tar.gz 10:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:38:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 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 10:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:38:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:38:02 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 10:38:02 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 10:38:02 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 10:38:02 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 10:38:02 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 10:38:02 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 10:38:02 INFO - Downloading docopt-0.6.1.tar.gz 10:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:38:02 INFO - Installing collected packages: blobuploader, requests, docopt 10:38:02 INFO - Running setup.py install for blobuploader 10:38:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:38:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:38:02 INFO - Running setup.py install for requests 10:38:03 INFO - Running setup.py install for docopt 10:38:03 INFO - Successfully installed blobuploader requests docopt 10:38:03 INFO - Cleaning up... 10:38:03 INFO - Return code: 0 10:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38: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')]} 10:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38: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')]} 10:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:03 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:38:03 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 10:38:03 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 10:38:03 INFO - Using env: {'DISPLAY': ':0', 10:38:03 INFO - 'HOME': '/home/cltbld', 10:38:03 INFO - 'LANG': 'en_US.UTF-8', 10:38:03 INFO - 'LANGUAGE': 'en_US:en', 10:38:03 INFO - 'LOGNAME': 'cltbld', 10:38:03 INFO - 'MAIL': '/var/mail/cltbld', 10:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:03 INFO - 'MOZ_NO_REMOTE': '1', 10:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:03 INFO - 'NO_EM_RESTART': '1', 10:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:03 INFO - 'PWD': '/builds/slave/test', 10:38:03 INFO - 'SHELL': '/bin/bash', 10:38:03 INFO - 'SHLVL': '1', 10:38:03 INFO - 'TERM': 'linux', 10:38:03 INFO - 'TMOUT': '86400', 10:38:03 INFO - 'USER': 'cltbld', 10:38:03 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:03 INFO - '_': '/tools/buildbot/bin/python'} 10:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:38:03 INFO - Running setup.py (path:/tmp/pip-JD0LNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:38:03 INFO - Running setup.py (path:/tmp/pip-J1HYkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:38:03 INFO - Running setup.py (path:/tmp/pip-hWbOu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:38:03 INFO - Running setup.py (path:/tmp/pip-OjFftO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:38:03 INFO - Running setup.py (path:/tmp/pip-HKn5EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:38:03 INFO - Running setup.py (path:/tmp/pip-YBHbkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:38:03 INFO - Running setup.py (path:/tmp/pip-UxL4vE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:38:03 INFO - Running setup.py (path:/tmp/pip-KD38vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:38:04 INFO - Running setup.py (path:/tmp/pip-QPp9rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:38:04 INFO - Running setup.py (path:/tmp/pip-l8StC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:38:04 INFO - Running setup.py (path:/tmp/pip-t7ZdbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:38:04 INFO - Running setup.py (path:/tmp/pip-MVn797-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:38:04 INFO - Running setup.py (path:/tmp/pip-Bld4tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:38:04 INFO - Running setup.py (path:/tmp/pip-07qfuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:04 INFO - Running setup.py (path:/tmp/pip-wV2aKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:38:04 INFO - Running setup.py (path:/tmp/pip-HrEL9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:38:04 INFO - Running setup.py (path:/tmp/pip-CEcQFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:38:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:38:04 INFO - Running setup.py install for manifestparser 10:38:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:38:04 INFO - Running setup.py install for mozcrash 10:38:04 INFO - Running setup.py install for mozdebug 10:38:04 INFO - Running setup.py install for mozdevice 10:38:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Running setup.py install for mozfile 10:38:05 INFO - Running setup.py install for mozhttpd 10:38:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Running setup.py install for mozinfo 10:38:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Running setup.py install for mozInstall 10:38:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Running setup.py install for mozleak 10:38:05 INFO - Running setup.py install for mozlog 10:38:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Running setup.py install for moznetwork 10:38:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:38:05 INFO - Running setup.py install for mozprocess 10:38:05 INFO - Running setup.py install for mozprofile 10:38:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:38:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:38:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:38:06 INFO - Running setup.py install for mozrunner 10:38:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:38:06 INFO - Running setup.py install for mozscreenshot 10:38:06 INFO - Running setup.py install for moztest 10:38:06 INFO - Running setup.py install for mozversion 10:38:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:38:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:38:06 INFO - Cleaning up... 10:38:06 INFO - Return code: 0 10:38:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38:06 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')]} 10:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:06 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')]} 10:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:06 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:38:06 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 10:38:06 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 10:38:06 INFO - Using env: {'DISPLAY': ':0', 10:38:06 INFO - 'HOME': '/home/cltbld', 10:38:06 INFO - 'LANG': 'en_US.UTF-8', 10:38:06 INFO - 'LANGUAGE': 'en_US:en', 10:38:06 INFO - 'LOGNAME': 'cltbld', 10:38:06 INFO - 'MAIL': '/var/mail/cltbld', 10:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:06 INFO - 'MOZ_NO_REMOTE': '1', 10:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:06 INFO - 'NO_EM_RESTART': '1', 10:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:06 INFO - 'PWD': '/builds/slave/test', 10:38:06 INFO - 'SHELL': '/bin/bash', 10:38:06 INFO - 'SHLVL': '1', 10:38:06 INFO - 'TERM': 'linux', 10:38:06 INFO - 'TMOUT': '86400', 10:38:06 INFO - 'USER': 'cltbld', 10:38:06 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:06 INFO - '_': '/tools/buildbot/bin/python'} 10:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:38:06 INFO - Running setup.py (path:/tmp/pip-fKkBfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:38:06 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)) 10:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:38:06 INFO - Running setup.py (path:/tmp/pip-Dve9A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:38:06 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)) 10:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:38:06 INFO - Running setup.py (path:/tmp/pip-zyHo_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:38:06 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)) 10:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:38:06 INFO - Running setup.py (path:/tmp/pip-y5Jg2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:38:06 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)) 10:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:38:06 INFO - Running setup.py (path:/tmp/pip-x5z_0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:38:07 INFO - Running setup.py (path:/tmp/pip-5KOesw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:38:07 INFO - Running setup.py (path:/tmp/pip-oP_YgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:38:07 INFO - Running setup.py (path:/tmp/pip-ekUZDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:38:07 INFO - Running setup.py (path:/tmp/pip-r6ncgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:38:07 INFO - Running setup.py (path:/tmp/pip-KaiWdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:38:07 INFO - Running setup.py (path:/tmp/pip-RA18H5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:38:07 INFO - Running setup.py (path:/tmp/pip-ejtzT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:38:07 INFO - Running setup.py (path:/tmp/pip-CPBSKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:38:07 INFO - Running setup.py (path:/tmp/pip-2e64Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:07 INFO - Running setup.py (path:/tmp/pip-MqYsx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:38:07 INFO - Running setup.py (path:/tmp/pip-dHVTGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:38:07 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)) 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:38:07 INFO - Running setup.py (path:/tmp/pip-OuBYoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:38:07 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)) 10:38:07 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)) 10:38:07 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)) 10:38:07 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)) 10:38:07 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)) 10:38:07 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)) 10:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:38:07 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 10:38:07 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 10:38:07 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 10:38:07 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 10:38:07 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 10:38:07 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 10:38:09 INFO - Downloading blessings-1.6.tar.gz 10:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:38:09 INFO - Installing collected packages: blessings 10:38:09 INFO - Running setup.py install for blessings 10:38:09 INFO - Successfully installed blessings 10:38:09 INFO - Cleaning up... 10:38:10 INFO - Return code: 0 10:38:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:38:10 INFO - Reading from file tmpfile_stdout 10:38:10 INFO - Current package versions: 10:38:10 INFO - argparse == 1.2.1 10:38:10 INFO - blessings == 1.6 10:38:10 INFO - blobuploader == 1.2.4 10:38:10 INFO - docopt == 0.6.1 10:38:10 INFO - functools32 == 3.2.3-2 10:38:10 INFO - jsonschema == 2.5.1 10:38:10 INFO - manifestparser == 1.1 10:38:10 INFO - mozInstall == 1.12 10:38:10 INFO - mozcrash == 0.17 10:38:10 INFO - mozdebug == 0.1 10:38:10 INFO - mozdevice == 0.48 10:38:10 INFO - mozfile == 1.2 10:38:10 INFO - mozhttpd == 0.7 10:38:10 INFO - mozinfo == 0.9 10:38:10 INFO - mozleak == 0.1 10:38:10 INFO - mozlog == 3.3 10:38:10 INFO - moznetwork == 0.27 10:38:10 INFO - mozprocess == 0.23 10:38:10 INFO - mozprofile == 0.28 10:38:10 INFO - mozrunner == 6.12 10:38:10 INFO - mozscreenshot == 0.1 10:38:10 INFO - mozsystemmonitor == 0.3 10:38:10 INFO - moztest == 0.7 10:38:10 INFO - mozversion == 1.4 10:38:10 INFO - psutil == 3.1.1 10:38:10 INFO - requests == 1.2.3 10:38:10 INFO - wsgiref == 0.1.2 10:38:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:10 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:38:10 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 10:38:10 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 10:38:10 INFO - Using env: {'DISPLAY': ':0', 10:38:10 INFO - 'HOME': '/home/cltbld', 10:38:10 INFO - 'LANG': 'en_US.UTF-8', 10:38:10 INFO - 'LANGUAGE': 'en_US:en', 10:38:10 INFO - 'LOGNAME': 'cltbld', 10:38:10 INFO - 'MAIL': '/var/mail/cltbld', 10:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:10 INFO - 'MOZ_NO_REMOTE': '1', 10:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:10 INFO - 'NO_EM_RESTART': '1', 10:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:10 INFO - 'PWD': '/builds/slave/test', 10:38:10 INFO - 'SHELL': '/bin/bash', 10:38:10 INFO - 'SHLVL': '1', 10:38:10 INFO - 'TERM': 'linux', 10:38:10 INFO - 'TMOUT': '86400', 10:38:10 INFO - 'USER': 'cltbld', 10:38:10 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:10 INFO - '_': '/tools/buildbot/bin/python'} 10:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 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)) 10:38:10 INFO - Cleaning up... 10:38:10 INFO - Return code: 0 10:38:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:10 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 0x2082030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2343180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', '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 10:38:10 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 10:38:10 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 10:38:10 INFO - Using env: {'DISPLAY': ':0', 10:38:10 INFO - 'HOME': '/home/cltbld', 10:38:10 INFO - 'LANG': 'en_US.UTF-8', 10:38:10 INFO - 'LANGUAGE': 'en_US:en', 10:38:10 INFO - 'LOGNAME': 'cltbld', 10:38:10 INFO - 'MAIL': '/var/mail/cltbld', 10:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:10 INFO - 'MOZ_NO_REMOTE': '1', 10:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:10 INFO - 'NO_EM_RESTART': '1', 10:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:10 INFO - 'PWD': '/builds/slave/test', 10:38:10 INFO - 'SHELL': '/bin/bash', 10:38:10 INFO - 'SHLVL': '1', 10:38:10 INFO - 'TERM': 'linux', 10:38:10 INFO - 'TMOUT': '86400', 10:38:10 INFO - 'USER': 'cltbld', 10:38:10 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:10 INFO - '_': '/tools/buildbot/bin/python'} 10:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:38:10 INFO - Cleaning up... 10:38:10 INFO - Return code: 0 10:38:10 INFO - Running post-action listener: _resource_record_post_action 10:38:10 INFO - Running post-action listener: _start_resource_monitoring 10:38:10 INFO - Starting resource monitoring. 10:38:10 INFO - [mozharness: 2016-08-26 17:38:10.718052Z] Finished create-virtualenv step (success) 10:38:10 INFO - [mozharness: 2016-08-26 17:38:10.718517Z] Running install step. 10:38:10 INFO - Running pre-action listener: _resource_record_pre_action 10:38:10 INFO - Running main action method: install 10:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:38:10 INFO - Reading from file tmpfile_stdout 10:38:10 INFO - Detecting whether we're running mozinstall >=1.0... 10:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:38:11 INFO - Reading from file tmpfile_stdout 10:38:11 INFO - Output received: 10:38:11 INFO - Usage: mozinstall [options] installer 10:38:11 INFO - Options: 10:38:11 INFO - -h, --help show this help message and exit 10:38:11 INFO - -d DEST, --destination=DEST 10:38:11 INFO - Directory to install application into. [default: 10:38:11 INFO - "/builds/slave/test"] 10:38:11 INFO - --app=APP Application being installed. [default: firefox] 10:38:11 INFO - mkdir: /builds/slave/test/build/application 10:38:11 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'] 10:38:11 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 10:38:26 INFO - Reading from file tmpfile_stdout 10:38:26 INFO - Output received: 10:38:26 INFO - /builds/slave/test/build/application/firefox/firefox 10:38:26 INFO - Running post-action listener: _resource_record_post_action 10:38:26 INFO - [mozharness: 2016-08-26 17:38:26.998806Z] Finished install step (success) 10:38:26 INFO - [mozharness: 2016-08-26 17:38:26.999080Z] Running run-tests step. 10:38:26 INFO - Running pre-action listener: _resource_record_pre_action 10:38:26 INFO - Running main action method: run_tests 10:38:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:38:27 INFO - Python 2.7.3 10:38:27 INFO - Return code: 0 10:38:27 INFO - grabbing minidump binary from tooltool 10:38: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')]} 10:38:27 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 0x23413b0>, '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 0x23422b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2342740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:38:27 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 10:38:27 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 10:38:27 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 10:38:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:38:27 INFO - Return code: 0 10:38:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:38:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:38:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:38:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:38:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:38:27 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:38:27 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:38:27 INFO - Using env: {'DISPLAY': ':0', 10:38:27 INFO - 'HOME': '/home/cltbld', 10:38:27 INFO - 'LANG': 'en_US.UTF-8', 10:38:27 INFO - 'LANGUAGE': 'en_US:en', 10:38:27 INFO - 'LOGNAME': 'cltbld', 10:38:27 INFO - 'MAIL': '/var/mail/cltbld', 10:38:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:38:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:27 INFO - 'MOZ_NO_REMOTE': '1', 10:38:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:38:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:27 INFO - 'NO_EM_RESTART': '1', 10:38:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:27 INFO - 'PWD': '/builds/slave/test', 10:38:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:38:27 INFO - 'SHELL': '/bin/bash', 10:38:27 INFO - 'SHLVL': '1', 10:38:27 INFO - 'TERM': 'linux', 10:38:27 INFO - 'TMOUT': '86400', 10:38:27 INFO - 'USER': 'cltbld', 10:38:27 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184', 10:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:27 INFO - '_': '/tools/buildbot/bin/python'} 10:38:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:38:27 INFO - mozversion application_buildid: 20160826071918 10:38:27 INFO - mozversion application_changeset: e58e63e362ff2a402bc7f22f0193097d07c418f1 10:38:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:38:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:38:27 INFO - mozversion application_name: Firefox 10:38:27 INFO - mozversion application_remotingname: firefox-dev 10:38:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:38:27 INFO - mozversion application_vendor: Mozilla 10:38:27 INFO - mozversion application_version: 50.0a2 10:38:27 INFO - mozversion platform_buildid: 20160826071918 10:38:27 INFO - mozversion platform_changeset: e58e63e362ff2a402bc7f22f0193097d07c418f1 10:38:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:38:27 INFO - mozversion platform_version: 50.0a2 10:38:27 INFO - using testdate: 1472233107 10:38:27 INFO - actual date: 1472233107 10:38:27 INFO - starting webserver on 'localhost:59640' 10:38:27 INFO - SUITE-START | Running 2 tests 10:38:27 INFO - TEST-START | dromaeo_css 10:38:27 INFO - operating with platform_type : linux_ 10:38:27 INFO - Initialising browser for dromaeo_css test... 10:38:27 INFO - TEST-INFO | started process 12649 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjCJTZQ/profile http://localhost:59640/getInfo.html) 10:38:31 INFO - PROCESS | 12649 | __metrics Screen width/height:1600/1200 10:38:31 INFO - PROCESS | 12649 | colorDepth:24 10:38:31 INFO - PROCESS | 12649 | Browser inner width/height: 1024/697 10:38:31 INFO - PROCESS | 12649 | __metrics 10:38:32 INFO - PROCESS | 12649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:38:32 INFO - PROCESS | 12649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:38:33 INFO - TEST-INFO | 12649: exit 0 10:38:33 INFO - Browser initialized. 10:38:33 INFO - Running cycle 1/1 for dromaeo_css test... 10:38:33 INFO - TEST-INFO | started process 12736 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjCJTZQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:45 INFO - PROCESS | 12736 | RSS: Main: 145412096 10:38:45 INFO - PROCESS | 12736 | 10:39:36 INFO - PROCESS | 12736 | 1472233176512 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] 10:41:44 INFO - PROCESS | 12736 | Cycle 1(1): loaded http://localhost:59640/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59640/tests/dromaeo/cssquery-ext.html) 10:41:46 INFO - PROCESS | 12736 | RSS: Main: 274059264 10:41:46 INFO - PROCESS | 12736 | 10:44:48 INFO - PROCESS | 12736 | Cycle 1(1): loaded http://localhost:59640/tests/dromaeo/cssquery-ext.html (next: http://localhost:59640/tests/dromaeo/cssquery-jquery.html) 10:44:49 INFO - PROCESS | 12736 | RSS: Main: 225763328 10:44:49 INFO - PROCESS | 12736 | 10:47:51 INFO - PROCESS | 12736 | Cycle 1(1): loaded http://localhost:59640/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59640/tests/dromaeo/cssquery-mootools.html) 10:47:51 INFO - PROCESS | 12736 | RSS: Main: 174141440 10:47:51 INFO - PROCESS | 12736 | 10:50:53 INFO - PROCESS | 12736 | Cycle 1(1): loaded http://localhost:59640/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59640/tests/dromaeo/cssquery-prototype.html) 10:50:54 INFO - PROCESS | 12736 | RSS: Main: 224563200 10:50:54 INFO - PROCESS | 12736 | 10:53:56 INFO - PROCESS | 12736 | Cycle 1(1): loaded http://localhost:59640/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59640/tests/dromaeo/cssquery-yui.html) 10:53:57 INFO - PROCESS | 12736 | RSS: Main: 175587328 10:53:57 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | Cycle 1(1): loaded http://localhost:59640/tests/dromaeo/cssquery-yui.html (next: http://localhost:59640/tests/dromaeo/cssquery-dojo.html) 10:57:00 INFO - PROCESS | 12736 | RSS: Main: 263069696 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | __start_tp_report 10:57:00 INFO - PROCESS | 12736 | _x_x_mozilla_page_load 10:57:00 INFO - PROCESS | 12736 | _x_x_mozilla_page_load_details 10:57:00 INFO - PROCESS | 12736 | |i|pagename|runs| 10:57:00 INFO - PROCESS | 12736 | |0;dojo.html;3243.7567182461307;3290.0623322353135;3316.485944678575;3319.2531680371903;3335.1161942085337;5640.971795141024;5659.207710920469;5698.34469036061;5714.51426628737;5725.685087320207;5676.489115979561;5728.369879313283;5733.569982251327;5738.7417566209515;5740.225069615601;4978.576820970222;5042.142835717918;5162.535371816545;5201.55786758126;5262.315898933152;5088.974555127219;5091.694498330094;5100.795968161269;5136.357955255593;5137.332146820908;5201.505856943584;5213.869653258661;5227.372715274172;5232.947670523303;5235.921461178066;5230.110881150175;5232.581393488531;5237.214417837316;5237.554807841338;5263.973680131594;5009.12340340439;5060.291559181717;5095.286659867622;5123.513266239738;5125.410577783566;5580;5610.130429783349;5625.54995600351;5649.096144616841;5717.342505611867;6084.391560843925;6085.300190478108;6103.382399154001;6104.542159338023;6127.509799216096;5941.494972927307;6054.243219597551;6121.125664634936;6141.877162456726;6147.262328520606;5624.240727501776;5675.829725108253;5683.431656834367;5777.740001699934;5785.82642520725;6215.720292586754;6231.657258850716;6232.781852635136;6233.376662333731;6239.532035097295;5773.624714393551;5941.22764040672;5991.64050156992;6047;6048.304444988883;6110.052941794029;6119.755209791559;6130.141780150782;6135;6140.416660417253;6111.786087486917;6112.083187521818;6116.113163591254;6117.694115294306;6123.32428981808;5847.532197424196;5908.054711246266;6014.759409623566;6106.755729770848;6133.110698948627;4656.557627025427;4684.484706682262;4685.554872287127;4692.9530704692515;4718.905621887612;4174;4179.728317659343;4190.916181676411;4202.3696445533415;4221.64116050133;5585.106382978704;5676.545876329884;5689.6586204827845;5788.27646544182;5855.970720146372;6071.180390647294;6080.817575472743;6082.452579267887;6092.299385570627;6098.756049758049;5762.827115186551;5764.625299355528;5769.221155144001;5770.653760774367;5775.220345253337;5028.899422011539;5063.797448102108;5205.167173252262;5222.033923724123;5230.267762513251;6365.395287447685;6376.681165941684;6387.297397286295;6389.329120442287;6393.6163830170335;6124.663143527148;6137.3862613738265;6138.109974053648;6138.877222455615;6142.6314421134875;6118.497354391859;6202.255729312511;6211.65835879013;6385.361463853763;6401.519886008661;5974.984252677143;5976.073708575178;5978.582548706519;5986.910196347146;6018.8194354169445;6071.757129714939;6075.696215189311;6078.696065196811;6080.391960803884;6081.817545473643;5772.942270577409;5866.50134738535;5885.94114058871;5923.644581325134;5963.075723262901;5953.910691339547;5962.105684147413;5963.582549221513;5981.641101533922;6001.099835024781;5867.149263356875;5869.178315035813;5886.028805247086;5931.64410135376;5932.13983972313;5749.942500575108;5928.970355148369;5943.257092863392;5949.91075133882;5978.671173085522;5888.381719919518;5891.793787217427;5896.55775816824;5916.881662366814;5931.3772053935445 10:57:00 INFO - PROCESS | 12736 | |1;ext.html;604.2356419129799;629.6505439481089;635.6440393379708;649.8180509457353;653.2226650286159;34193.487097693556;37184.25631487372;45304;46187.152513899404;46440.3033954491;48741.51258487414;49081.509184908144;49117;49176.50823491764;49206;9258.907410925905;9435.433873967584;9436.764080897998;9446.433214007182;9450.385724927857;8494.532800695959;10973.23187376884;11216.102711783038;11217.551297948072;11617.709557261094;11035;11080.556777728882;11081;11084.667459976174;11091.445427728622;11316.32102073874;11325.207235493519;11386.487608057658;11387.54449822006;11396.14528910331;11316.377599232039;11397.544098236061;11397.829032564518;11403.315801051964;11413.315201087962;45791.54208457906;46092.3086153708;46159;46219;46260.306095408596;3787.3750831112834;3790.223004284115;3816;3890.5525864525375;3907.5310962684375;34984.30031399383;35287;35381.46927796085;35659.10852228689;35909.46135807965;4627.167109920213;4669.41632295963;4710.222813235812;4735.881602959954;4760.880977975544;390666.2800116001;390948;391051;391824;392211;63727;64117.679411603574;64186.358136418974;64192;64254;39274.41088383677;39735.40396894049;39951.2009759809;41886.371704424455;65006.674966624865;19388.224471021;19528.51178720515;19552.706709399372;19563.80436195648;19578.510537236398;14105.85894141066;14488.782668259984;15156.469523566622;15305;15324.23378831054;19989.800101999084;20976.580468390548;21016.579668406546;21068.15727370919;21088.156473740877;7442.851142977219;7612.048493938258;7629.732959346507;7637.4653774236385;7690.384769218449;1069.4919913041242;1123.6909849791373;1124.9100071994221;1147.1568397228032;1147.2141583015493;34166.829165854506;34826.47760283598;34977;34980.47529287063;35017.474737878954;1165.1261553834624;1167.2996202278564;1227.2575092069349;1241.416534228911;1243.8507379114565;14372.425102995763;16397.672046559;17731.468055958343;19317.22731090769;19404.417867464;616208;624375;627295;629022;630931;63596.682016590545;64094.35905640884;64197;64205.357946420874;64366;1354.525915929417;1439.1365180891382;1446.2551785830306;1489.4116823854733;1516.924153792328;31675.20811979719;32814.5077823828;33870.491942621375;33872.830635846665;34214;50911.49088509067;51348.74325628348;51389.22916156335;51408.74295628648;51450.22824657708;338895;339956;340362;340824;341455;30783.2304192397;31764.04707858768;32008.359832802747;33306.66693333036;33350;53789.46210537844;53808.46191538034;53863.46136538585;53912.460875390745;54299.72850135724;44546.3318050223;45637.77181114073;45716;46552;47551.28673069976;45331;45783.77108114438;46015.539844601124;46593.877754512185;47372.52627473681;44658.77670611626;44683.329750053126;45724.314135287335;46083.53916460792;46243;39362;40398;40928;41052.794736027325;41383.37924931068;30249;30297.545536816524;30410.239744006576;30766;30909.845450772602 10:57:00 INFO - PROCESS | 12736 | |2;jquery.html;397.10849143672425;421.7912133493923;427.23524890446095;433.40406940456865;441.0649423222768;16201;21756.238531651394;21912.45218869527;21914.45213869654;22401;20590.073446694896;20693.172273109056;21046;21088.57822843542;21108;5454.10007348787;6067.059605761114;6100.42046005631;6114.419130182626;6116.357782432848;5820.883582328351;5926.229590153274;5937.3287500622855;6009.09863520471;6009.549283803711;5913.940860591403;5922.911156332637;5942.792002279921;5949.107633854913;5955.464008239279;5734.827955161331;5911.467967882912;5936.317323507808;5941.762329506814;5948.256467941507;5326.6005049621435;5372.0867452532775;5375.408705042442;5375.973120134374;5389;24548.140815071387;24552.5089498209;25154.496910061695;25458.236252912444;25815.225543233544;2584.3926677230834;2603.1279521360366;2686.6507354043847;2740.287525243431;2872.741453269216;14694.412223511046;15158.469453569072;15240.847591524054;15994.44019459325;16407.91796041024;1935.138863205874;2005.5387260930067;2062.4431403520966;2157.3959291398432;2189.7043899073738;72352;73824;74821.25178748198;75127.24872751257;87102.56448717717;10252.487375631188;11540.769184616262;11934.761304774029;11940.88059119415;11958.521659133537;16380.180990950405;16460.588485287724;16495.927764695258;16550.172491375622;16560.917195413946;8857.424267422597;9205.079492050741;9349.953250233706;9395;9407.717768466957;9196.540172991323;9252.583633736498;9284.257259419366;9385.624575016924;9480.620775168916;4995.725235112031;5085.847424577269;5099.311592934981;5109.335786347751;5151.2530683050745;1982.5440148765576;1987.274644754683;2022.797720227995;2076.5704126172413;2099.2127952017995;986.8618393424927;1018.9643362482426;1026.5277972132903;1045.491813461213;1111.0112000319723;20583.4854128645;20629.58740825175;21082.262120825697;21082.683759743617;21356.252531161335;1024.2113572549094;1038.948052597367;1047.9790404192026;1052.984205236922;1088.7495875948418;9763.463009534402;12008.219465734699;12027.977621902237;12034.458449369798;12047.518099276105;511942;521630;523471;524385;531606;66128.66935665387;67606.32393676;90128;106313;109496.4525177385;1010.4291075542295;1078.5739632844982;1111.927724697892;1116.4696769034806;1132.0999805155086;18910.716339255196;18926.52683682919;19499.317523886595;19536.511587210432;19570.804291956898;46280.991735537085;46550.30174547317;46893.765531172125;47930.281045783646;48873.75563122162;61031;65629.34370656424;65719;66673;67034.32965670282;8811.471311721318;8908.73273801787;8949.105089491259;8960.103989600988;9166.495842728447;9504.95247523758;12754.68113297175;13619.931900340434;13705.520306789214;14054.78917816254;11984.880151198377;12016.75966480683;12037.939810300892;12273.509059637514;12713.757991712055;11543.8268425972;11893.46479408446;11963.222390544586;12113.69715757078;12209.450574724337;11611.419429028683;11804.114691398008;12259.448324825582;12587;12676.746465070462;8875.556222188736;9008.324375671982;9129.863052054092;9174.862377064215;9202.447853128566;10043.89956100459;11878.762424751629;11907.106966977339;12123.81814272769;12126.575569855013 10:57:00 INFO - PROCESS | 12736 | |3;mootools.html;1534.1255484373905;1601.4955289083937;1747.2224859937326;1851.8888866667992;1882.2941396976144;9078;9711.708648740549;9717.514124293792;9727.22182225422;9729.610815567386;8242.216989386014;8437.240648341649;9004.639814407416;9253.167214950654;9551.856722149174;2465.1495234144236;2492.9002839886384;2495.8252922295446;2497.625356196567;2498.3504288884837;2435.7077150741934;2488.738682438345;2514.786243169333;2527.4692314613862;2534.2270607464716;2441.56051910656;2480.900763969439;2504.586743187372;2507.862067586282;2511.472590755934;2421.733609302984;2424.733279339271;2431.4772323950347;2486.950260994788;2497.9750202498108;2418.22616762636;2428.8299819012677;2443.1815341860497;2444.64552639868;2468.4322605800585;9521.857172142423;9575.664851730226;9606.90393096067;9608.327417080807;9698.272629552812;5402.162664786964;5551.972885016245;5558.610897237195;5564.221009058735;5596.86007849807;3472.6700963408434;3494.5457090578316;3554.5379100717;3579.785212887235;3609.224016836393;1215.9209651372632;1232.599405193307;1239.5599562155485;1253.9623811285585;1266.5756971414587;12800.295983720891;12818.487260509568;12885.613431597067;12891.484340626364;12896.161749486253;4704.482506924235;4762;4893.608511319086;4964.180910149785;5008.273800298936;10954.178436617285;10964;10975.06711929479;10995.945020275007;11022.889771102185;5643.125315576092;5648.491635752802;5654.095344744904;5666.914996275059;5668.744906479218;4568.2690769477385;4951.059298733229;5080.898382032413;5130.51260130287;5203.245529398216;4335.566443355639;4339.804708788112;4366.104948485617;4366.825326986885;4423.535528769497;1317.3610798762593;1325.9071864969553;1335.6327010072114;1344.072589912957;1352.8714772096635;914.3416739947228;977.5845265762023;1008.4655132779639;1038.8396806934797;1076.8492411062457;6353.841153971188;6375.234971803413;6583.703733332011;6728.798136055828;6830.248672646005;435.3208993969355;444.9866504004885;449.2721790699088;451.1000553895;452.86414075777134;4552.863414097516;4618.330342100377;4642.442906851199;4684;4687.859364219079;11425.657230283105;11695.181337306307;11777.352245626402;11828.23116497425;11841.697413284532;11330.206885518099;11754.11844111695;11756.764864702658;11763.176577639482;11811.2322699026;8736.650533978696;8896.421732587361;8923.464592124494;8939.955300223588;8959;6488.826243208389;6617;6626.56927299724;6665.933340666726;6762.256151823394;10685.305455145392;10806.243562950518;10837.241393102408;10958.342499450058;11035.282706624044;11088.611898583511;11126.721831954266;11128.554857805597;11130.276532025393;11233.49449274801;8271.627776750182;8316.251537361666;8466.195711407407;8488.193621605938;8490.490570565786;8799.252063574668;8820.955895220482;8881.156290152425;8882.955585222291;8893.822123557622;8061;8180.509169449852;8206.835863282582;8210.83578328442;8537.231649151607;8068.838623227385;8158.184181581794;8165.38759593021;8257.710980115668;8276.337892968428;7774.611269436393;7877.684892604231;7901.209879012053;7914;7941.364690824605;5547.81720755242;5568.415316391883;5571.721413929368;5705.743241554057;5774.105013722879;5613.663180209201;5676.262085928803;5684.317881854204;5727.770889164553;5736.110902810071 10:57:00 INFO - PROCESS | 12736 | |4;prototype.html;339.42128670616603;341.8342104079521;352.7072529800266;356;360.0243340547116;5600.747966341514;5631.155326700997;5631.77472901084;5637.689927054011;5641.576881733876;5241.554467870226;5425.3489581250205;5435.918461223085;5436.20605200284;5554.361248456429;4253.766042867649;4341.218580655481;4359.847405340814;4377.6716746244;4396.934045989313;4371.759553224571;4378.1462614790125;4384.583464570861;4387.473503179623;4394.516603173648;4149.83400663973;4307.375430562566;4325.783710814462;4345.739255644656;4377.402248179407;4302.41917341158;4311.8490852820005;4318.52496225415;4324.416203812508;4328.264195086827;3804.733668643196;3819.312523745725;3820.2168555446065;3830.3871380579253;3836.0601652595014;5277.683338999673;5351;5438.972805135949;5484.670919744828;5526.834194974117;4942.135126352874;4945.901081978341;4947.925781113287;4954.612708441642;4966.602671786284;5808.361080281165;5832.416758324176;5851.268591426072;5861.941380586182;5867.8533036674;4045.292073887058;4111.403846442249;4170.832166993217;4171.644215629902;4223.915521689549;6022.96988515059;6024.246969128859;6040.607360521552;6049.78825741097;6062.696865156769;4226.57734226581;4234.920095375721;4250.873518517596;4264.957350426518;4294.806733696991;4236.894077648022;4306.569343065731;4315.654747620183;4353.564643535685;4356.607905288539;4918.926216106761;4928.728919909439;4929.235968424829;4931.506849315111;4946.530079642428;5858.912116318259;5859.326177489558;5860.501857342174;5867.589268751147;5880.2653217300995;1415.801787749716;1416.8653977872086;1456.424712238681;1482.4292647330856;1485.6731519065788;977.7995510920244;1001.7796084861319;1005.3364779245662;1016.3139880580578;1027.9486025698686;4849.199882019428;5048.469910659402;5060.924086138711;5084.237364395371;5103.923441148386;5277.366715994029;5281.735913204325;5294.470552944675;5296.92054619181;5323.973380133075;4856.077345304381;5039.571636410946;5045.672031317952;5047.722375269395;5050.419201791768;2828.406034732688;2828.57571364297;2835.6530647942063;2838.929026774347;2840.772738180982;5421.864453388697;5463.644863083887;5469.507744303032;5487.396386397493;5516.503514683617;5171.741412929338;5371.4897084776885;5387.542058925035;5407.378151512627;5415.889742602757;5464.207689885024;5472.699001554953;5474.42518535556;5480.808171713971;5487.42382049887;5241.554467870274;5242.58059355243;5244.737763111905;5286.15421532553;5315.681059136464;4950.702957822542;5128.891665551049;5182.481751824787;5184.9740751296;5260.789568417374;5390.245365648891;5423.755930983037;5424.481145279328;5466.863328416822;5485.32697527258;3834.654881060718;3835.1946091321133;3856.9228615428096;3885.7085718571784;3888.561232344051;5259.89480210386;5628.718564071862;6007.789727359522;6081.84444955457;6094.908576371269;5668.574856885668;5781.797637082682;5912.822615321547;5921.437463440966;5937.2578427696535;5890.7054647266605;5906.970465147647;5918.378570250234;5931.61444506123;5950.434708702666;5703.20155178267;5850.23946886902;5910.438508341701;5911.7635294587735;5931.555133364928;5920.8815823684145;5931.3178984417345;5934.732937017759;5936.22829032223;5954.4938680210935;5674.716264186692;5789.334226563999;5800.9419905800405;5811.169481613279;5812.447817457335 10:57:00 INFO - PROCESS | 12736 | |5;yui.html;806.4556424413521;814.1858141858141;819.135811718637;836.6026137584644;932.0772435289064;16324.755128673065;17476.388326408578;17505.7374139388;17535.736963945536;17559.648807023867;15914.52256432306;16004.119773412458;16025;16043.438479653214;16044.75932861008;669.5246375073704;861.9827603447927;871.6034204436997;885.8361203177401;890.688259109313;868.2706526517724;872.2760109109456;874.5802015032756;875.2166810704416;876.2770714160843;937.6483818568037;937.746808361742;938.7418459923541;938.8873335199786;939.0562484702864;760.4144808497454;891.1756625121769;920.2132176988712;925.7315378540249;934.8504239321676;677.6408503493157;681.5535824035264;681.7034589953386;682.2665634442993;682.6586706646676;14921;14976.400943962228;14993.925030374778;15008.024478408977;15074.246287685572;14425.350859211361;14708.926455367762;14964.47624333154;14997.625059373493;15065;13529.797053044213;13554.11898226622;13568.660783480393;13861.792073118912;13971.510997115052;4770.952290477086;4831.396075490564;4865.41615006198;4913.748039728154;4935.802567897315;85727;86008.56995714981;88087.55956220177;89456.55271723664;89825;11308.717282068013;11969.700757481132;12380.566680166152;12381.566645167555;12388.256704597752;22835.429114272276;22983.425414364774;23041.654375184386;23142.07431702713;23156.07375704953;8739.737807865775;8961.641534338683;9194.954025229965;9254.213391861767;9270.768230794283;4792.376990991149;4939.753012349368;4944.406671199479;5018.573421259234;5156.329677142023;3489.965100349015;3616.692581130581;3626.3653860574295;3631.7276204284785;3687.870924517683;694.3126304958055;700.4659795048929;704.1128178495132;709.7090193020837;718.9928100718926;752.7440670172168;757.1103952855373;759.4152502572988;765.4106338119617;819.1767273889822;8634.395592308478;9245.676401325922;9754.804903901882;9832.754181145528;10107.494625268708;626.674129452682;695.5861262548775;699.3111784891878;739.9825240294595;817.0644611919319;4685.156671799109;4846.248831431134;4883.780229889663;5112.36095488064;5157.690538567698;115537.4223128879;116938.8306116928;120139;122311;124631;100181.49909250553;100661.49669251754;101312;103014.81911085358;104348;84034.5798271017;90001;91203;92697.53651231836;92708;9489.525523723649;9895.56514305436;9896.20830333586;9907.851382229128;10207.948960255151;53728.73135634297;54924.45075549193;54943.45056549383;55385.1692224625;55701;70122.64938675275;80077;80936.59531702539;81120.59439702764;81653.59173204096;13568.253746044062;13705.520306789214;13854.515091971733;13881.86118138846;13902.374393152535;15470.704084214303;15632.843671563594;16505.257263422936;16830.915845420695;17005.404810831562;13693.178409295077;14564.198969056804;14729.705405892037;15270.618234544225;15728.76406853921;14711;14822.740067093986;15452.5364239073;15767.842321577096;15949.760753588938;14438;14506.637334066732;14581.270936453348;14862.256887155816;15005.549833505012;11310;12129.636110916686;12181.39093045362;12288.201266917622;12353.440698419305;11698.76602467963;12093.153479256367;12096.153269271419;12476.875231247572;12597.496100156257 10:57:00 INFO - PROCESS | 12736 | __end_tp_report 10:57:00 INFO - PROCESS | 12736 | __start_cc_report 10:57:00 INFO - PROCESS | 12736 | _x_x_mozilla_cycle_collect,1112 10:57:00 INFO - PROCESS | 12736 | __end_cc_report 10:57:00 INFO - PROCESS | 12736 | __startTimestamp1472234220581__endTimestamp 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | ------- Summary: start ------- 10:57:00 INFO - PROCESS | 12736 | Number of tests: 6 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | [#0] dojo.html Cycles:165 Average:5650.94 Median:5885.94 stddev:642.75 (10.9%) stddev-sans-first:616.35 10:57:00 INFO - PROCESS | 12736 | Values: 3243.8 3290.1 3316.5 3319.3 3335.1 5641.0 5659.2 5698.3 5714.5 5725.7 5676.5 5728.4 5733.6 5738.7 5740.2 4978.6 5042.1 5162.5 5201.6 5262.3 5089.0 5091.7 5100.8 5136.4 5137.3 5201.5 5213.9 5227.4 5232.9 5235.9 5230.1 5232.6 5237.2 5237.6 5264.0 5009.1 5060.3 5095.3 5123.5 5125.4 5580.0 5610.1 5625.5 5649.1 5717.3 6084.4 6085.3 6103.4 6104.5 6127.5 5941.5 6054.2 6121.1 6141.9 6147.3 5624.2 5675.8 5683.4 5777.7 5785.8 6215.7 6231.7 6232.8 6233.4 6239.5 5773.6 5941.2 5991.6 6047.0 6048.3 6110.1 6119.8 6130.1 6135.0 6140.4 6111.8 6112.1 6116.1 6117.7 6123.3 5847.5 5908.1 6014.8 6106.8 6133.1 4656.6 4684.5 4685.6 4693.0 4718.9 4174.0 4179.7 4190.9 4202.4 4221.6 5585.1 5676.5 5689.7 5788.3 5856.0 6071.2 6080.8 6082.5 6092.3 6098.8 5762.8 5764.6 5769.2 5770.7 5775.2 5028.9 5063.8 5205.2 5222.0 5230.3 6365.4 6376.7 6387.3 6389.3 6393.6 6124.7 6137.4 6138.1 6138.9 6142.6 6118.5 6202.3 6211.7 6385.4 6401.5 5975.0 5976.1 5978.6 5986.9 6018.8 6071.8 6075.7 6078.7 6080.4 6081.8 5772.9 5866.5 5885.9 5923.6 5963.1 5953.9 5962.1 5963.6 5981.6 6001.1 5867.1 5869.2 5886.0 5931.6 5932.1 5749.9 5929.0 5943.3 5949.9 5978.7 5888.4 5891.8 5896.6 5916.9 5931.4 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | [#1] ext.html Cycles:180 Average:62905.82 Median:197286.91 stddev:124579.09 (63.1%) stddev-sans-first:124840.75 10:57:00 INFO - PROCESS | 12736 | Values: 604.2 629.7 635.6 649.8 653.2 34193.5 37184.3 45304.0 46187.2 46440.3 48741.5 49081.5 49117.0 49176.5 49206.0 9258.9 9435.4 9436.8 9446.4 9450.4 8494.5 10973.2 11216.1 11217.6 11617.7 11035.0 11080.6 11081.0 11084.7 11091.4 11316.3 11325.2 11386.5 11387.5 11396.1 11316.4 11397.5 11397.8 11403.3 11413.3 45791.5 46092.3 46159.0 46219.0 46260.3 3787.4 3790.2 3816.0 3890.6 3907.5 34984.3 35287.0 35381.5 35659.1 35909.5 4627.2 4669.4 4710.2 4735.9 4760.9 390666.3 390948.0 391051.0 391824.0 392211.0 63727.0 64117.7 64186.4 64192.0 64254.0 39274.4 39735.4 39951.2 41886.4 65006.7 19388.2 19528.5 19552.7 19563.8 19578.5 14105.9 14488.8 15156.5 15305.0 15324.2 19989.8 20976.6 21016.6 21068.2 21088.2 7442.9 7612.0 7629.7 7637.5 7690.4 1069.5 1123.7 1124.9 1147.2 1147.2 34166.8 34826.5 34977.0 34980.5 35017.5 1165.1 1167.3 1227.3 1241.4 1243.9 14372.4 16397.7 17731.5 19317.2 19404.4 616208.0 624375.0 627295.0 629022.0 630931.0 63596.7 64094.4 64197.0 64205.4 64366.0 1354.5 1439.1 1446.3 1489.4 1516.9 31675.2 32814.5 33870.5 33872.8 34214.0 50911.5 51348.7 51389.2 51408.7 51450.2 338895.0 339956.0 340362.0 340824.0 341455.0 30783.2 31764.0 32008.4 33306.7 33350.0 53789.5 53808.5 53863.5 53912.5 54299.7 44546.3 45637.8 45716.0 46552.0 47551.3 45331.0 45783.8 46015.5 46593.9 47372.5 44658.8 44683.3 45724.3 46083.5 46243.0 39362.0 40398.0 40928.0 41052.8 41383.4 30249.0 30297.5 30410.2 30766.0 30909.8 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | [#2] jquery.html Cycles:180 Average:30660.23 Median:24853.50 stddev:85902.53 (345.6%) stddev-sans-first:86113.46 10:57:00 INFO - PROCESS | 12736 | Values: 397.1 421.8 427.2 433.4 441.1 16201.0 21756.2 21912.5 21914.5 22401.0 20590.1 20693.2 21046.0 21088.6 21108.0 5454.1 6067.1 6100.4 6114.4 6116.4 5820.9 5926.2 5937.3 6009.1 6009.5 5913.9 5922.9 5942.8 5949.1 5955.5 5734.8 5911.5 5936.3 5941.8 5948.3 5326.6 5372.1 5375.4 5376.0 5389.0 24548.1 24552.5 25154.5 25458.2 25815.2 2584.4 2603.1 2686.7 2740.3 2872.7 14694.4 15158.5 15240.8 15994.4 16407.9 1935.1 2005.5 2062.4 2157.4 2189.7 72352.0 73824.0 74821.3 75127.2 87102.6 10252.5 11540.8 11934.8 11940.9 11958.5 16380.2 16460.6 16495.9 16550.2 16560.9 8857.4 9205.1 9350.0 9395.0 9407.7 9196.5 9252.6 9284.3 9385.6 9480.6 4995.7 5085.8 5099.3 5109.3 5151.3 1982.5 1987.3 2022.8 2076.6 2099.2 986.9 1019.0 1026.5 1045.5 1111.0 20583.5 20629.6 21082.3 21082.7 21356.3 1024.2 1038.9 1048.0 1053.0 1088.7 9763.5 12008.2 12028.0 12034.5 12047.5 511942.0 521630.0 523471.0 524385.0 531606.0 66128.7 67606.3 90128.0 106313.0 109496.5 1010.4 1078.6 1111.9 1116.5 1132.1 18910.7 18926.5 19499.3 19536.5 19570.8 46281.0 46550.3 46893.8 47930.3 48873.8 61031.0 65629.3 65719.0 66673.0 67034.3 8811.5 8908.7 8949.1 8960.1 9166.5 9505.0 12754.7 13619.9 13705.5 14054.8 11984.9 12016.8 12037.9 12273.5 12713.8 11543.8 11893.5 11963.2 12113.7 12209.5 11611.4 11804.1 12259.4 12587.0 12676.7 8875.6 9008.3 9129.9 9174.9 9202.4 10043.9 11878.8 11907.1 12123.8 12126.6 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | [#3] mootools.html Cycles:180 Average:6223.24 Median:4630.39 stddev:3547.12 (76.6%) stddev-sans-first:3539.56 10:57:00 INFO - PROCESS | 12736 | Values: 1534.1 1601.5 1747.2 1851.9 1882.3 9078.0 9711.7 9717.5 9727.2 9729.6 8242.2 8437.2 9004.6 9253.2 9551.9 2465.1 2492.9 2495.8 2497.6 2498.4 2435.7 2488.7 2514.8 2527.5 2534.2 2441.6 2480.9 2504.6 2507.9 2511.5 2421.7 2424.7 2431.5 2487.0 2498.0 2418.2 2428.8 2443.2 2444.6 2468.4 9521.9 9575.7 9606.9 9608.3 9698.3 5402.2 5552.0 5558.6 5564.2 5596.9 3472.7 3494.5 3554.5 3579.8 3609.2 1215.9 1232.6 1239.6 1254.0 1266.6 12800.3 12818.5 12885.6 12891.5 12896.2 4704.5 4762.0 4893.6 4964.2 5008.3 10954.2 10964.0 10975.1 10995.9 11022.9 5643.1 5648.5 5654.1 5666.9 5668.7 4568.3 4951.1 5080.9 5130.5 5203.2 4335.6 4339.8 4366.1 4366.8 4423.5 1317.4 1325.9 1335.6 1344.1 1352.9 914.3 977.6 1008.5 1038.8 1076.8 6353.8 6375.2 6583.7 6728.8 6830.2 435.3 445.0 449.3 451.1 452.9 4552.9 4618.3 4642.4 4684.0 4687.9 11425.7 11695.2 11777.4 11828.2 11841.7 11330.2 11754.1 11756.8 11763.2 11811.2 8736.7 8896.4 8923.5 8940.0 8959.0 6488.8 6617.0 6626.6 6665.9 6762.3 10685.3 10806.2 10837.2 10958.3 11035.3 11088.6 11126.7 11128.6 11130.3 11233.5 8271.6 8316.3 8466.2 8488.2 8490.5 8799.3 8821.0 8881.2 8883.0 8893.8 8061.0 8180.5 8206.8 8210.8 8537.2 8068.8 8158.2 8165.4 8257.7 8276.3 7774.6 7877.7 7901.2 7914.0 7941.4 5547.8 5568.4 5571.7 5705.7 5774.1 5613.7 5676.3 5684.3 5727.8 5736.1 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | [#4] prototype.html Cycles:180 Average:4725.27 Median:5242.07 stddev:1370.37 (26.1%) stddev-sans-first:1334.09 10:57:00 INFO - PROCESS | 12736 | Values: 339.4 341.8 352.7 356.0 360.0 5600.7 5631.2 5631.8 5637.7 5641.6 5241.6 5425.3 5435.9 5436.2 5554.4 4253.8 4341.2 4359.8 4377.7 4396.9 4371.8 4378.1 4384.6 4387.5 4394.5 4149.8 4307.4 4325.8 4345.7 4377.4 4302.4 4311.8 4318.5 4324.4 4328.3 3804.7 3819.3 3820.2 3830.4 3836.1 5277.7 5351.0 5439.0 5484.7 5526.8 4942.1 4945.9 4947.9 4954.6 4966.6 5808.4 5832.4 5851.3 5861.9 5867.9 4045.3 4111.4 4170.8 4171.6 4223.9 6023.0 6024.2 6040.6 6049.8 6062.7 4226.6 4234.9 4250.9 4265.0 4294.8 4236.9 4306.6 4315.7 4353.6 4356.6 4918.9 4928.7 4929.2 4931.5 4946.5 5858.9 5859.3 5860.5 5867.6 5880.3 1415.8 1416.9 1456.4 1482.4 1485.7 977.8 1001.8 1005.3 1016.3 1027.9 4849.2 5048.5 5060.9 5084.2 5103.9 5277.4 5281.7 5294.5 5296.9 5324.0 4856.1 5039.6 5045.7 5047.7 5050.4 2828.4 2828.6 2835.7 2838.9 2840.8 5421.9 5463.6 5469.5 5487.4 5516.5 5171.7 5371.5 5387.5 5407.4 5415.9 5464.2 5472.7 5474.4 5480.8 5487.4 5241.6 5242.6 5244.7 5286.2 5315.7 4950.7 5128.9 5182.5 5185.0 5260.8 5390.2 5423.8 5424.5 5466.9 5485.3 3834.7 3835.2 3856.9 3885.7 3888.6 5259.9 5628.7 6007.8 6081.8 6094.9 5668.6 5781.8 5912.8 5921.4 5937.3 5890.7 5907.0 5918.4 5931.6 5950.4 5703.2 5850.2 5910.4 5911.8 5931.6 5920.9 5931.3 5934.7 5936.2 5954.5 5674.7 5789.3 5800.9 5811.2 5812.4 10:57:00 INFO - PROCESS | 12736 | 10:57:00 INFO - PROCESS | 12736 | [#5] yui.html Cycles:180 Average:22184.84 Median:4838.82 stddev:31622.42 (653.5%) stddev-sans-first:31670.38 10:57:00 INFO - PROCESS | 12736 | Values: 806.5 814.2 819.1 836.6 932.1 16324.8 17476.4 17505.7 17535.7 17559.6 15914.5 16004.1 16025.0 16043.4 16044.8 669.5 862.0 871.6 885.8 890.7 868.3 872.3 874.6 875.2 876.3 937.6 937.7 938.7 938.9 939.1 760.4 891.2 920.2 925.7 934.9 677.6 681.6 681.7 682.3 682.7 14921.0 14976.4 14993.9 15008.0 15074.2 14425.4 14708.9 14964.5 14997.6 15065.0 13529.8 13554.1 13568.7 13861.8 13971.5 4771.0 4831.4 4865.4 4913.7 4935.8 85727.0 86008.6 88087.6 89456.6 89825.0 11308.7 11969.7 12380.6 12381.6 12388.3 22835.4 22983.4 23041.7 23142.1 23156.1 8739.7 8961.6 9195.0 9254.2 9270.8 4792.4 4939.8 4944.4 5018.6 5156.3 3490.0 3616.7 3626.4 3631.7 3687.9 694.3 700.5 704.1 709.7 719.0 752.7 757.1 759.4 765.4 819.2 8634.4 9245.7 9754.8 9832.8 10107.5 626.7 695.6 699.3 740.0 817.1 4685.2 4846.2 4883.8 5112.4 5157.7 115537.4 116938.8 120139.0 122311.0 124631.0 100181.5 100661.5 101312.0 103014.8 104348.0 84034.6 90001.0 91203.0 92697.5 92708.0 9489.5 9895.6 9896.2 9907.9 10207.9 53728.7 54924.5 54943.5 55385.2 55701.0 70122.6 80077.0 80936.6 81120.6 81653.6 13568.3 13705.5 13854.5 13881.9 13902.4 15470.7 15632.8 16505.3 16830.9 17005.4 13693.2 14564.2 14729.7 15270.6 15728.8 14711.0 14822.7 15452.5 15767.8 15949.8 14438.0 14506.6 14581.3 14862.3 15005.5 11310.0 12129.6 12181.4 12288.2 12353.4 11698.8 12093.2 12096.2 12476.9 12597.5 10:57:00 INFO - PROCESS | 12736 | -------- Summary: end -------- 10:57:00 INFO - PROCESS | 12736 | 10:57:01 INFO - TEST-INFO | 12736: exit 0 10:57:01 INFO - TEST-OK | dromaeo_css | took 1114076ms 10:57:01 INFO - TEST-START | kraken 10:57:01 INFO - operating with platform_type : linux_ 10:57:01 INFO - Initialising browser for kraken test... 10:57:01 INFO - TEST-INFO | started process 12935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUolk4y/profile http://localhost:59640/getInfo.html) 10:57:05 INFO - PROCESS | 12935 | __metrics Screen width/height:1600/1200 10:57:05 INFO - PROCESS | 12935 | colorDepth:24 10:57:05 INFO - PROCESS | 12935 | Browser inner width/height: 1024/697 10:57:05 INFO - PROCESS | 12935 | __metrics 10:57:06 INFO - PROCESS | 12935 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:57:06 INFO - PROCESS | 12935 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:57:07 INFO - TEST-INFO | 12935: exit 0 10:57:07 INFO - Browser initialized. 10:57:07 INFO - Running cycle 1/1 for kraken test... 10:57:07 INFO - TEST-INFO | started process 13027 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUolk4y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:57:19 INFO - PROCESS | 13027 | RSS: Main: 145330176 10:57:19 INFO - PROCESS | 13027 | 10:57:21 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59640/tests/kraken/driver.html?testName=audio-beat-detection) 10:57:22 INFO - PROCESS | 13027 | RSS: Main: 191655936 10:57:22 INFO - PROCESS | 13027 | 10:57:24 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59640/tests/kraken/driver.html?testName=audio-dft) 10:57:25 INFO - PROCESS | 13027 | RSS: Main: 181493760 10:57:25 INFO - PROCESS | 13027 | 10:57:28 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59640/tests/kraken/driver.html?testName=audio-fft) 10:57:28 INFO - PROCESS | 13027 | RSS: Main: 269783040 10:57:28 INFO - PROCESS | 13027 | 10:57:30 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59640/tests/kraken/driver.html?testName=audio-oscillator) 10:57:31 INFO - PROCESS | 13027 | RSS: Main: 175456256 10:57:31 INFO - PROCESS | 13027 | 10:57:33 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59640/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:57:34 INFO - PROCESS | 13027 | RSS: Main: 176828416 10:57:34 INFO - PROCESS | 13027 | 10:57:37 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59640/tests/kraken/driver.html?testName=imaging-darkroom) 10:57:37 INFO - PROCESS | 13027 | RSS: Main: 212877312 10:57:37 INFO - PROCESS | 13027 | 10:57:41 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59640/tests/kraken/driver.html?testName=imaging-desaturate) 10:57:41 INFO - PROCESS | 13027 | RSS: Main: 211722240 10:57:41 INFO - PROCESS | 13027 | 10:57:44 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59640/tests/kraken/driver.html?testName=json-parse-financial) 10:57:45 INFO - PROCESS | 13027 | RSS: Main: 211718144 10:57:45 INFO - PROCESS | 13027 | 10:57:46 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59640/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:57:47 INFO - PROCESS | 13027 | RSS: Main: 176709632 10:57:47 INFO - PROCESS | 13027 | 10:57:49 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:57:49 INFO - PROCESS | 13027 | RSS: Main: 182812672 10:57:49 INFO - PROCESS | 13027 | 10:57:51 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:57:52 INFO - PROCESS | 13027 | RSS: Main: 186281984 10:57:52 INFO - PROCESS | 13027 | 10:57:54 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:57:55 INFO - PROCESS | 13027 | RSS: Main: 181542912 10:57:55 INFO - PROCESS | 13027 | 10:57:57 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:57:58 INFO - PROCESS | 13027 | RSS: Main: 184606720 10:57:58 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | Cycle 1(1): loaded http://localhost:59640/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59640/tests/kraken/driver.html?testName=ai-astar) 10:58:00 INFO - PROCESS | 13027 | RSS: Main: 236294144 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | __start_tp_report 10:58:00 INFO - PROCESS | 13027 | _x_x_mozilla_page_load 10:58:00 INFO - PROCESS | 13027 | _x_x_mozilla_page_load_details 10:58:00 INFO - PROCESS | 13027 | |i|pagename|runs| 10:58:00 INFO - PROCESS | 13027 | |0;ai-astar;101;112;112;111;112;111;112;112;110;110 10:58:00 INFO - PROCESS | 13027 | |1;audio-beat-detection;159;113;117;174;114;120;139;114;146;113 10:58:00 INFO - PROCESS | 13027 | |2;audio-dft;162;161;168;162;160;159;161;161;160;158 10:58:00 INFO - PROCESS | 13027 | |3;audio-fft;73;75;76;106;75;75;74;75;100;74 10:58:00 INFO - PROCESS | 13027 | |4;audio-oscillator;83;84;126;84;84;83;114;83;83;131 10:58:00 INFO - PROCESS | 13027 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;116;117 10:58:00 INFO - PROCESS | 13027 | |6;imaging-darkroom;148;149;149;150;149;148;148;149;149;146 10:58:00 INFO - PROCESS | 13027 | |7;imaging-desaturate;95;95;95;96;95;94;95;95;94;95 10:58:00 INFO - PROCESS | 13027 | |8;json-parse-financial;50;49;49;50;52;50;49;51;50;49 10:58:00 INFO - PROCESS | 13027 | |9;json-stringify-tinderbox;61;61;60;61;62;60;60;61;61;61 10:58:00 INFO - PROCESS | 13027 | |10;stanford-crypto-aes;72;77;73;72;71;72;72;71;71;71 10:58:00 INFO - PROCESS | 13027 | |11;stanford-crypto-ccm;119;116;115;118;115;126;121;115;160;132 10:58:00 INFO - PROCESS | 13027 | |12;stanford-crypto-pbkdf2;149;150;153;150;153;146;151;147;149;148 10:58:00 INFO - PROCESS | 13027 | |13;stanford-crypto-sha256-iterative;60;61;60;61;61;60;61;60;60;60 10:58:00 INFO - PROCESS | 13027 | __end_tp_report 10:58:00 INFO - PROCESS | 13027 | __start_cc_report 10:58:00 INFO - PROCESS | 13027 | _x_x_mozilla_cycle_collect,1599 10:58:00 INFO - PROCESS | 13027 | __end_cc_report 10:58:00 INFO - PROCESS | 13027 | __startTimestamp1472234280422__endTimestamp 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | ------- Summary: start ------- 10:58:00 INFO - PROCESS | 13027 | Number of tests: 14 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#0] ai-astar Cycles:10 Average:110.30 Median:112.00 stddev:3.37 (3.0%) stddev-sans-first:0.87 10:58:00 INFO - PROCESS | 13027 | Values: 101.0 112.0 112.0 111.0 112.0 111.0 112.0 112.0 110.0 110.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:129.50 stddev:22.27 (17.2%) stddev-sans-first:21.18 10:58:00 INFO - PROCESS | 13027 | Values: 159.0 113.0 117.0 174.0 114.0 120.0 139.0 114.0 146.0 113.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:2.70 (1.7%) stddev-sans-first:2.85 10:58:00 INFO - PROCESS | 13027 | Values: 162.0 161.0 168.0 162.0 160.0 159.0 161.0 161.0 160.0 158.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.00 stddev:12.07 (16.1%) stddev-sans-first:12.51 10:58:00 INFO - PROCESS | 13027 | Values: 73.0 75.0 76.0 106.0 75.0 75.0 74.0 75.0 100.0 74.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:83.00 stddev:19.87 (23.9%) stddev-sans-first:20.56 10:58:00 INFO - PROCESS | 13027 | Values: 83.0 84.0 126.0 84.0 84.0 83.0 114.0 83.0 83.0 131.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:58:00 INFO - PROCESS | 13027 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 10:58:00 INFO - PROCESS | 13027 | Values: 148.0 149.0 149.0 150.0 149.0 148.0 148.0 149.0 149.0 146.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 10:58:00 INFO - PROCESS | 13027 | Values: 95.0 95.0 95.0 96.0 95.0 94.0 95.0 95.0 94.0 95.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:0.99 (2.0%) stddev-sans-first:1.05 10:58:00 INFO - PROCESS | 13027 | Values: 50.0 49.0 49.0 50.0 52.0 50.0 49.0 51.0 50.0 49.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 10:58:00 INFO - PROCESS | 13027 | Values: 61.0 61.0 60.0 61.0 62.0 60.0 60.0 61.0 61.0 61.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#10] stanford-crypto-aes Cycles:10 Average:72.20 Median:72.00 stddev:1.81 (2.5%) stddev-sans-first:1.92 10:58:00 INFO - PROCESS | 13027 | Values: 72.0 77.0 73.0 72.0 71.0 72.0 72.0 71.0 71.0 71.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:120.00 stddev:13.90 (11.6%) stddev-sans-first:14.64 10:58:00 INFO - PROCESS | 13027 | Values: 119.0 116.0 115.0 118.0 115.0 126.0 121.0 115.0 160.0 132.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.60 Median:150.00 stddev:2.32 (1.5%) stddev-sans-first:2.45 10:58:00 INFO - PROCESS | 13027 | Values: 149.0 150.0 153.0 150.0 153.0 146.0 151.0 147.0 149.0 148.0 10:58:00 INFO - PROCESS | 13027 | 10:58:00 INFO - PROCESS | 13027 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 10:58:00 INFO - PROCESS | 13027 | Values: 60.0 61.0 60.0 61.0 61.0 60.0 61.0 60.0 60.0 60.0 10:58:00 INFO - PROCESS | 13027 | -------- Summary: end -------- 10:58:00 INFO - PROCESS | 13027 | 10:58:01 INFO - TEST-INFO | 13027: exit 0 10:58:01 INFO - TEST-OK | kraken | took 60105ms 10:58:01 INFO - SUITE-END | took 1174s 10:58:01 INFO - Completed test suite (00:19:34) 10:58:01 INFO - javascript benchmark 10:58:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3243.7567182461307, 3290.0623322353135, 3316.485944678575, 3319.2531680371903, 3335.1161942085337, 5640.971795141024, 5659.207710920469, 5698.34469036061, 5714.51426628737, 5725.685087320207, 5676.489115979561, 5728.369879313283, 5733.569982251327, 5738.7417566209515, 5740.225069615601, 4978.576820970222, 5042.142835717918, 5162.535371816545, 5201.55786758126, 5262.315898933152, 5088.974555127219, 5091.694498330094, 5100.795968161269, 5136.357955255593, 5137.332146820908, 5201.505856943584, 5213.869653258661, 5227.372715274172, 5232.947670523303, 5235.921461178066, 5230.110881150175, 5232.581393488531, 5237.214417837316, 5237.554807841338, 5263.973680131594, 5009.12340340439, 5060.291559181717, 5095.286659867622, 5123.513266239738, 5125.410577783566, 5580.0, 5610.130429783349, 5625.54995600351, 5649.096144616841, 5717.342505611867, 6084.391560843925, 6085.300190478108, 6103.382399154001, 6104.542159338023, 6127.509799216096, 5941.494972927307, 6054.243219597551, 6121.125664634936, 6141.877162456726, 6147.262328520606, 5624.240727501776, 5675.829725108253, 5683.431656834367, 5777.740001699934, 5785.82642520725, 6215.720292586754, 6231.657258850716, 6232.781852635136, 6233.376662333731, 6239.532035097295, 5773.624714393551, 5941.22764040672, 5991.64050156992, 6047.0, 6048.304444988883, 6110.052941794029, 6119.755209791559, 6130.141780150782, 6135.0, 6140.416660417253, 6111.786087486917, 6112.083187521818, 6116.113163591254, 6117.694115294306, 6123.32428981808, 5847.532197424196, 5908.054711246266, 6014.759409623566, 6106.755729770848, 6133.110698948627, 4656.557627025427, 4684.484706682262, 4685.554872287127, 4692.9530704692515, 4718.905621887612, 4174.0, 4179.728317659343, 4190.916181676411, 4202.3696445533415, 4221.64116050133, 5585.106382978704, 5676.545876329884, 5689.6586204827845, 5788.27646544182, 5855.970720146372, 6071.180390647294, 6080.817575472743, 6082.452579267887, 6092.299385570627, 6098.756049758049, 5762.827115186551, 5764.625299355528, 5769.221155144001, 5770.653760774367, 5775.220345253337, 5028.899422011539, 5063.797448102108, 5205.167173252262, 5222.033923724123, 5230.267762513251, 6365.395287447685, 6376.681165941684, 6387.297397286295, 6389.329120442287, 6393.6163830170335, 6124.663143527148, 6137.3862613738265, 6138.109974053648, 6138.877222455615, 6142.6314421134875, 6118.497354391859, 6202.255729312511, 6211.65835879013, 6385.361463853763, 6401.519886008661, 5974.984252677143, 5976.073708575178, 5978.582548706519, 5986.910196347146, 6018.8194354169445, 6071.757129714939, 6075.696215189311, 6078.696065196811, 6080.391960803884, 6081.817545473643, 5772.942270577409, 5866.50134738535, 5885.94114058871, 5923.644581325134, 5963.075723262901, 5953.910691339547, 5962.105684147413, 5963.582549221513, 5981.641101533922, 6001.099835024781, 5867.149263356875, 5869.178315035813, 5886.028805247086, 5931.64410135376, 5932.13983972313, 5749.942500575108, 5928.970355148369, 5943.257092863392, 5949.91075133882, 5978.671173085522, 5888.381719919518, 5891.793787217427, 5896.55775816824, 5916.881662366814, 5931.3772053935445], "value": 5607.691374316514, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.2356419129799, 629.6505439481089, 635.6440393379708, 649.8180509457353, 653.2226650286159, 34193.487097693556, 37184.25631487372, 45304.0, 46187.152513899404, 46440.3033954491, 48741.51258487414, 49081.509184908144, 49117.0, 49176.50823491764, 49206.0, 9258.907410925905, 9435.433873967584, 9436.764080897998, 9446.433214007182, 9450.385724927857, 8494.532800695959, 10973.23187376884, 11216.102711783038, 11217.551297948072, 11617.709557261094, 11035.0, 11080.556777728882, 11081.0, 11084.667459976174, 11091.445427728622, 11316.32102073874, 11325.207235493519, 11386.487608057658, 11387.54449822006, 11396.14528910331, 11316.377599232039, 11397.544098236061, 11397.829032564518, 11403.315801051964, 11413.315201087962, 45791.54208457906, 46092.3086153708, 46159.0, 46219.0, 46260.306095408596, 3787.3750831112834, 3790.223004284115, 3816.0, 3890.5525864525375, 3907.5310962684375, 34984.30031399383, 35287.0, 35381.46927796085, 35659.10852228689, 35909.46135807965, 4627.167109920213, 4669.41632295963, 4710.222813235812, 4735.881602959954, 4760.880977975544, 390666.2800116001, 390948.0, 391051.0, 391824.0, 392211.0, 63727.0, 64117.679411603574, 64186.358136418974, 64192.0, 64254.0, 39274.41088383677, 39735.40396894049, 39951.2009759809, 41886.371704424455, 65006.674966624865, 19388.224471021, 19528.51178720515, 19552.706709399372, 19563.80436195648, 19578.510537236398, 14105.85894141066, 14488.782668259984, 15156.469523566622, 15305.0, 15324.23378831054, 19989.800101999084, 20976.580468390548, 21016.579668406546, 21068.15727370919, 21088.156473740877, 7442.851142977219, 7612.048493938258, 7629.732959346507, 7637.4653774236385, 7690.384769218449, 1069.4919913041242, 1123.6909849791373, 1124.9100071994221, 1147.1568397228032, 1147.2141583015493, 34166.829165854506, 34826.47760283598, 34977.0, 34980.47529287063, 35017.474737878954, 1165.1261553834624, 1167.2996202278564, 1227.2575092069349, 1241.416534228911, 1243.8507379114565, 14372.425102995763, 16397.672046559, 17731.468055958343, 19317.22731090769, 19404.417867464, 616208.0, 624375.0, 627295.0, 629022.0, 630931.0, 63596.682016590545, 64094.35905640884, 64197.0, 64205.357946420874, 64366.0, 1354.525915929417, 1439.1365180891382, 1446.2551785830306, 1489.4116823854733, 1516.924153792328, 31675.20811979719, 32814.5077823828, 33870.491942621375, 33872.830635846665, 34214.0, 50911.49088509067, 51348.74325628348, 51389.22916156335, 51408.74295628648, 51450.22824657708, 338895.0, 339956.0, 340362.0, 340824.0, 341455.0, 30783.2304192397, 31764.04707858768, 32008.359832802747, 33306.66693333036, 33350.0, 53789.46210537844, 53808.46191538034, 53863.46136538585, 53912.460875390745, 54299.72850135724, 44546.3318050223, 45637.77181114073, 45716.0, 46552.0, 47551.28673069976, 45331.0, 45783.77108114438, 46015.539844601124, 46593.877754512185, 47372.52627473681, 44658.77670611626, 44683.329750053126, 45724.314135287335, 46083.53916460792, 46243.0, 39362.0, 40398.0, 40928.0, 41052.794736027325, 41383.37924931068, 30249.0, 30297.545536816524, 30410.239744006576, 30766.0, 30909.845450772602], "value": 21857.696701021585, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.10849143672425, 421.7912133493923, 427.23524890446095, 433.40406940456865, 441.0649423222768, 16201.0, 21756.238531651394, 21912.45218869527, 21914.45213869654, 22401.0, 20590.073446694896, 20693.172273109056, 21046.0, 21088.57822843542, 21108.0, 5454.10007348787, 6067.059605761114, 6100.42046005631, 6114.419130182626, 6116.357782432848, 5820.883582328351, 5926.229590153274, 5937.3287500622855, 6009.09863520471, 6009.549283803711, 5913.940860591403, 5922.911156332637, 5942.792002279921, 5949.107633854913, 5955.464008239279, 5734.827955161331, 5911.467967882912, 5936.317323507808, 5941.762329506814, 5948.256467941507, 5326.6005049621435, 5372.0867452532775, 5375.408705042442, 5375.973120134374, 5389.0, 24548.140815071387, 24552.5089498209, 25154.496910061695, 25458.236252912444, 25815.225543233544, 2584.3926677230834, 2603.1279521360366, 2686.6507354043847, 2740.287525243431, 2872.741453269216, 14694.412223511046, 15158.469453569072, 15240.847591524054, 15994.44019459325, 16407.91796041024, 1935.138863205874, 2005.5387260930067, 2062.4431403520966, 2157.3959291398432, 2189.7043899073738, 72352.0, 73824.0, 74821.25178748198, 75127.24872751257, 87102.56448717717, 10252.487375631188, 11540.769184616262, 11934.761304774029, 11940.88059119415, 11958.521659133537, 16380.180990950405, 16460.588485287724, 16495.927764695258, 16550.172491375622, 16560.917195413946, 8857.424267422597, 9205.079492050741, 9349.953250233706, 9395.0, 9407.717768466957, 9196.540172991323, 9252.583633736498, 9284.257259419366, 9385.624575016924, 9480.620775168916, 4995.725235112031, 5085.847424577269, 5099.311592934981, 5109.335786347751, 5151.2530683050745, 1982.5440148765576, 1987.274644754683, 2022.797720227995, 2076.5704126172413, 2099.2127952017995, 986.8618393424927, 1018.9643362482426, 1026.5277972132903, 1045.491813461213, 1111.0112000319723, 20583.4854128645, 20629.58740825175, 21082.262120825697, 21082.683759743617, 21356.252531161335, 1024.2113572549094, 1038.948052597367, 1047.9790404192026, 1052.984205236922, 1088.7495875948418, 9763.463009534402, 12008.219465734699, 12027.977621902237, 12034.458449369798, 12047.518099276105, 511942.0, 521630.0, 523471.0, 524385.0, 531606.0, 66128.66935665387, 67606.32393676, 90128.0, 106313.0, 109496.4525177385, 1010.4291075542295, 1078.5739632844982, 1111.927724697892, 1116.4696769034806, 1132.0999805155086, 18910.716339255196, 18926.52683682919, 19499.317523886595, 19536.511587210432, 19570.804291956898, 46280.991735537085, 46550.30174547317, 46893.765531172125, 47930.281045783646, 48873.75563122162, 61031.0, 65629.34370656424, 65719.0, 66673.0, 67034.32965670282, 8811.471311721318, 8908.73273801787, 8949.105089491259, 8960.103989600988, 9166.495842728447, 9504.95247523758, 12754.68113297175, 13619.931900340434, 13705.520306789214, 14054.78917816254, 11984.880151198377, 12016.75966480683, 12037.939810300892, 12273.509059637514, 12713.757991712055, 11543.8268425972, 11893.46479408446, 11963.222390544586, 12113.69715757078, 12209.450574724337, 11611.419429028683, 11804.114691398008, 12259.448324825582, 12587.0, 12676.746465070462, 8875.556222188736, 9008.324375671982, 9129.863052054092, 9174.862377064215, 9202.447853128566, 10043.89956100459, 11878.762424751629, 11907.106966977339, 12123.81814272769, 12126.575569855013], "value": 9853.50464368202, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.1255484373905, 1601.4955289083937, 1747.2224859937326, 1851.8888866667992, 1882.2941396976144, 9078.0, 9711.708648740549, 9717.514124293792, 9727.22182225422, 9729.610815567386, 8242.216989386014, 8437.240648341649, 9004.639814407416, 9253.167214950654, 9551.856722149174, 2465.1495234144236, 2492.9002839886384, 2495.8252922295446, 2497.625356196567, 2498.3504288884837, 2435.7077150741934, 2488.738682438345, 2514.786243169333, 2527.4692314613862, 2534.2270607464716, 2441.56051910656, 2480.900763969439, 2504.586743187372, 2507.862067586282, 2511.472590755934, 2421.733609302984, 2424.733279339271, 2431.4772323950347, 2486.950260994788, 2497.9750202498108, 2418.22616762636, 2428.8299819012677, 2443.1815341860497, 2444.64552639868, 2468.4322605800585, 9521.857172142423, 9575.664851730226, 9606.90393096067, 9608.327417080807, 9698.272629552812, 5402.162664786964, 5551.972885016245, 5558.610897237195, 5564.221009058735, 5596.86007849807, 3472.6700963408434, 3494.5457090578316, 3554.5379100717, 3579.785212887235, 3609.224016836393, 1215.9209651372632, 1232.599405193307, 1239.5599562155485, 1253.9623811285585, 1266.5756971414587, 12800.295983720891, 12818.487260509568, 12885.613431597067, 12891.484340626364, 12896.161749486253, 4704.482506924235, 4762.0, 4893.608511319086, 4964.180910149785, 5008.273800298936, 10954.178436617285, 10964.0, 10975.06711929479, 10995.945020275007, 11022.889771102185, 5643.125315576092, 5648.491635752802, 5654.095344744904, 5666.914996275059, 5668.744906479218, 4568.2690769477385, 4951.059298733229, 5080.898382032413, 5130.51260130287, 5203.245529398216, 4335.566443355639, 4339.804708788112, 4366.104948485617, 4366.825326986885, 4423.535528769497, 1317.3610798762593, 1325.9071864969553, 1335.6327010072114, 1344.072589912957, 1352.8714772096635, 914.3416739947228, 977.5845265762023, 1008.4655132779639, 1038.8396806934797, 1076.8492411062457, 6353.841153971188, 6375.234971803413, 6583.703733332011, 6728.798136055828, 6830.248672646005, 435.3208993969355, 444.9866504004885, 449.2721790699088, 451.1000553895, 452.86414075777134, 4552.863414097516, 4618.330342100377, 4642.442906851199, 4684.0, 4687.859364219079, 11425.657230283105, 11695.181337306307, 11777.352245626402, 11828.23116497425, 11841.697413284532, 11330.206885518099, 11754.11844111695, 11756.764864702658, 11763.176577639482, 11811.2322699026, 8736.650533978696, 8896.421732587361, 8923.464592124494, 8939.955300223588, 8959.0, 6488.826243208389, 6617.0, 6626.56927299724, 6665.933340666726, 6762.256151823394, 10685.305455145392, 10806.243562950518, 10837.241393102408, 10958.342499450058, 11035.282706624044, 11088.611898583511, 11126.721831954266, 11128.554857805597, 11130.276532025393, 11233.49449274801, 8271.627776750182, 8316.251537361666, 8466.195711407407, 8488.193621605938, 8490.490570565786, 8799.252063574668, 8820.955895220482, 8881.156290152425, 8882.955585222291, 8893.822123557622, 8061.0, 8180.509169449852, 8206.835863282582, 8210.83578328442, 8537.231649151607, 8068.838623227385, 8158.184181581794, 8165.38759593021, 8257.710980115668, 8276.337892968428, 7774.611269436393, 7877.684892604231, 7901.209879012053, 7914.0, 7941.364690824605, 5547.81720755242, 5568.415316391883, 5571.721413929368, 5705.743241554057, 5774.105013722879, 5613.663180209201, 5676.262085928803, 5684.317881854204, 5727.770889164553, 5736.110902810071], "value": 4881.182911020344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.42128670616603, 341.8342104079521, 352.7072529800266, 356.0, 360.0243340547116, 5600.747966341514, 5631.155326700997, 5631.77472901084, 5637.689927054011, 5641.576881733876, 5241.554467870226, 5425.3489581250205, 5435.918461223085, 5436.20605200284, 5554.361248456429, 4253.766042867649, 4341.218580655481, 4359.847405340814, 4377.6716746244, 4396.934045989313, 4371.759553224571, 4378.1462614790125, 4384.583464570861, 4387.473503179623, 4394.516603173648, 4149.83400663973, 4307.375430562566, 4325.783710814462, 4345.739255644656, 4377.402248179407, 4302.41917341158, 4311.8490852820005, 4318.52496225415, 4324.416203812508, 4328.264195086827, 3804.733668643196, 3819.312523745725, 3820.2168555446065, 3830.3871380579253, 3836.0601652595014, 5277.683338999673, 5351.0, 5438.972805135949, 5484.670919744828, 5526.834194974117, 4942.135126352874, 4945.901081978341, 4947.925781113287, 4954.612708441642, 4966.602671786284, 5808.361080281165, 5832.416758324176, 5851.268591426072, 5861.941380586182, 5867.8533036674, 4045.292073887058, 4111.403846442249, 4170.832166993217, 4171.644215629902, 4223.915521689549, 6022.96988515059, 6024.246969128859, 6040.607360521552, 6049.78825741097, 6062.696865156769, 4226.57734226581, 4234.920095375721, 4250.873518517596, 4264.957350426518, 4294.806733696991, 4236.894077648022, 4306.569343065731, 4315.654747620183, 4353.564643535685, 4356.607905288539, 4918.926216106761, 4928.728919909439, 4929.235968424829, 4931.506849315111, 4946.530079642428, 5858.912116318259, 5859.326177489558, 5860.501857342174, 5867.589268751147, 5880.2653217300995, 1415.801787749716, 1416.8653977872086, 1456.424712238681, 1482.4292647330856, 1485.6731519065788, 977.7995510920244, 1001.7796084861319, 1005.3364779245662, 1016.3139880580578, 1027.9486025698686, 4849.199882019428, 5048.469910659402, 5060.924086138711, 5084.237364395371, 5103.923441148386, 5277.366715994029, 5281.735913204325, 5294.470552944675, 5296.92054619181, 5323.973380133075, 4856.077345304381, 5039.571636410946, 5045.672031317952, 5047.722375269395, 5050.419201791768, 2828.406034732688, 2828.57571364297, 2835.6530647942063, 2838.929026774347, 2840.772738180982, 5421.864453388697, 5463.644863083887, 5469.507744303032, 5487.396386397493, 5516.503514683617, 5171.741412929338, 5371.4897084776885, 5387.542058925035, 5407.378151512627, 5415.889742602757, 5464.207689885024, 5472.699001554953, 5474.42518535556, 5480.808171713971, 5487.42382049887, 5241.554467870274, 5242.58059355243, 5244.737763111905, 5286.15421532553, 5315.681059136464, 4950.702957822542, 5128.891665551049, 5182.481751824787, 5184.9740751296, 5260.789568417374, 5390.245365648891, 5423.755930983037, 5424.481145279328, 5466.863328416822, 5485.32697527258, 3834.654881060718, 3835.1946091321133, 3856.9228615428096, 3885.7085718571784, 3888.561232344051, 5259.89480210386, 5628.718564071862, 6007.789727359522, 6081.84444955457, 6094.908576371269, 5668.574856885668, 5781.797637082682, 5912.822615321547, 5921.437463440966, 5937.2578427696535, 5890.7054647266605, 5906.970465147647, 5918.378570250234, 5931.61444506123, 5950.434708702666, 5703.20155178267, 5850.23946886902, 5910.438508341701, 5911.7635294587735, 5931.555133364928, 5920.8815823684145, 5931.3178984417345, 5934.732937017759, 5936.22829032223, 5954.4938680210935, 5674.716264186692, 5789.334226563999, 5800.9419905800405, 5811.169481613279, 5812.447817457335], "value": 4295.201372127991, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.4556424413521, 814.1858141858141, 819.135811718637, 836.6026137584644, 932.0772435289064, 16324.755128673065, 17476.388326408578, 17505.7374139388, 17535.736963945536, 17559.648807023867, 15914.52256432306, 16004.119773412458, 16025.0, 16043.438479653214, 16044.75932861008, 669.5246375073704, 861.9827603447927, 871.6034204436997, 885.8361203177401, 890.688259109313, 868.2706526517724, 872.2760109109456, 874.5802015032756, 875.2166810704416, 876.2770714160843, 937.6483818568037, 937.746808361742, 938.7418459923541, 938.8873335199786, 939.0562484702864, 760.4144808497454, 891.1756625121769, 920.2132176988712, 925.7315378540249, 934.8504239321676, 677.6408503493157, 681.5535824035264, 681.7034589953386, 682.2665634442993, 682.6586706646676, 14921.0, 14976.400943962228, 14993.925030374778, 15008.024478408977, 15074.246287685572, 14425.350859211361, 14708.926455367762, 14964.47624333154, 14997.625059373493, 15065.0, 13529.797053044213, 13554.11898226622, 13568.660783480393, 13861.792073118912, 13971.510997115052, 4770.952290477086, 4831.396075490564, 4865.41615006198, 4913.748039728154, 4935.802567897315, 85727.0, 86008.56995714981, 88087.55956220177, 89456.55271723664, 89825.0, 11308.717282068013, 11969.700757481132, 12380.566680166152, 12381.566645167555, 12388.256704597752, 22835.429114272276, 22983.425414364774, 23041.654375184386, 23142.07431702713, 23156.07375704953, 8739.737807865775, 8961.641534338683, 9194.954025229965, 9254.213391861767, 9270.768230794283, 4792.376990991149, 4939.753012349368, 4944.406671199479, 5018.573421259234, 5156.329677142023, 3489.965100349015, 3616.692581130581, 3626.3653860574295, 3631.7276204284785, 3687.870924517683, 694.3126304958055, 700.4659795048929, 704.1128178495132, 709.7090193020837, 718.9928100718926, 752.7440670172168, 757.1103952855373, 759.4152502572988, 765.4106338119617, 819.1767273889822, 8634.395592308478, 9245.676401325922, 9754.804903901882, 9832.754181145528, 10107.494625268708, 626.674129452682, 695.5861262548775, 699.3111784891878, 739.9825240294595, 817.0644611919319, 4685.156671799109, 4846.248831431134, 4883.780229889663, 5112.36095488064, 5157.690538567698, 115537.4223128879, 116938.8306116928, 120139.0, 122311.0, 124631.0, 100181.49909250553, 100661.49669251754, 101312.0, 103014.81911085358, 104348.0, 84034.5798271017, 90001.0, 91203.0, 92697.53651231836, 92708.0, 9489.525523723649, 9895.56514305436, 9896.20830333586, 9907.851382229128, 10207.948960255151, 53728.73135634297, 54924.45075549193, 54943.45056549383, 55385.1692224625, 55701.0, 70122.64938675275, 80077.0, 80936.59531702539, 81120.59439702764, 81653.59173204096, 13568.253746044062, 13705.520306789214, 13854.515091971733, 13881.86118138846, 13902.374393152535, 15470.704084214303, 15632.843671563594, 16505.257263422936, 16830.915845420695, 17005.404810831562, 13693.178409295077, 14564.198969056804, 14729.705405892037, 15270.618234544225, 15728.76406853921, 14711.0, 14822.740067093986, 15452.5364239073, 15767.842321577096, 15949.760753588938, 14438.0, 14506.637334066732, 14581.270936453348, 14862.256887155816, 15005.549833505012, 11310.0, 12129.636110916686, 12181.39093045362, 12288.201266917622, 12353.440698419305, 11698.76602467963, 12093.153479256367, 12096.153269271419, 12476.875231247572, 12597.496100156257], "value": 8115.2521826547145, "unit": "score"}], "name": "dromaeo_css", "value": 7681.969969601504, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 111.0, 112.0, 111.0, 112.0, 112.0, 110.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 113.0, 117.0, 174.0, 114.0, 120.0, 139.0, 114.0, 146.0, 113.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 168.0, 162.0, 160.0, 159.0, 161.0, 161.0, 160.0, 158.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 106.0, 75.0, 75.0, 74.0, 75.0, 100.0, 74.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 126.0, 84.0, 84.0, 83.0, 114.0, 83.0, 83.0, 131.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 150.0, 149.0, 148.0, 148.0, 149.0, 149.0, 146.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 50.0, 52.0, 50.0, 49.0, 51.0, 50.0, 49.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 61.0, 62.0, 60.0, 60.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 73.0, 72.0, 71.0, 72.0, 72.0, 71.0, 71.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 115.0, 118.0, 115.0, 126.0, 121.0, 115.0, 160.0, 132.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 153.0, 150.0, 153.0, 146.0, 151.0, 147.0, 149.0, 148.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 60.4, "unit": "score"}], "name": "kraken", "value": 1455.0, "alertThreshold": 2.0}]} 10:58:01 INFO - Return code: 0 10:58:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:58:01 INFO - # TBPL SUCCESS # 10:58:01 INFO - Running post-action listener: _resource_record_post_action 10:58:01 INFO - [mozharness: 2016-08-26 17:58:01.726629Z] Finished run-tests step (success) 10:58:01 INFO - Running post-run listener: _resource_record_post_run 10:58:01 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 270336; Write bytes: 327766016; Read time: 252; Write time: 325596 10:58:01 INFO - TinderboxPrint: CPU usage
13.0% 10:58:01 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 252 10:58:01 INFO - TinderboxPrint: I/O write bytes / time
327,766,016 / 325,596 10:58:01 INFO - TinderboxPrint: CPU idle
8,262.5 (87.0%) 10:58:01 INFO - TinderboxPrint: CPU user
1,188.3 (12.5%) 10:58:01 INFO - TinderboxPrint: Swap in / out
0 / 0 10:58:01 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78381056; Read time: 0; Write time: 117460 10:58:01 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 248102912; Read time: 248; Write time: 208088 10:58:02 INFO - Running post-run listener: _upload_blobber_files 10:58:02 INFO - Blob upload gear active. 10:58:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:58:02 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 10:58:02 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'] 10:58:02 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 10:58:02 INFO - (blobuploader) - INFO - Open directory for files ... 10:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:58:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:03 INFO - (blobuploader) - INFO - Done attempting. 10:58:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:58:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:58:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:12 INFO - (blobuploader) - INFO - Done attempting. 10:58:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:58:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:58:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:13 INFO - (blobuploader) - INFO - Done attempting. 10:58:13 INFO - (blobuploader) - INFO - Iteration through files over. 10:58:13 INFO - Return code: 0 10:58:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:58:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:58:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edfd1020e022434b13e883565840b4cfda5ffec0c18f048f92fcc74338752cbfc36b7deafb51a4142930ff33a4cd859691ff7b17b8bffa0961ec408461c21ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee52fcb6646b6e78216e6e821a86dc2f7f4d7bc833123252af9342bae5af42b9bbbcf1ff55132fe4a8c401cd8be4bf87327eccb8832fe7063a7812ea05e6d52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d2e36f9afcac0fdbf749ccbf558823667f01a49d95ddf3ea975101245df7351f12099a3cc4621ade938c4f4c424117980bcd09a2563f7042632afc961faacc"} 10:58:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:58:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:58:13 INFO - Contents: 10:58:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edfd1020e022434b13e883565840b4cfda5ffec0c18f048f92fcc74338752cbfc36b7deafb51a4142930ff33a4cd859691ff7b17b8bffa0961ec408461c21ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee52fcb6646b6e78216e6e821a86dc2f7f4d7bc833123252af9342bae5af42b9bbbcf1ff55132fe4a8c401cd8be4bf87327eccb8832fe7063a7812ea05e6d52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d2e36f9afcac0fdbf749ccbf558823667f01a49d95ddf3ea975101245df7351f12099a3cc4621ade938c4f4c424117980bcd09a2563f7042632afc961faacc"} 10:58:13 INFO - Running post-run listener: copy_logs_to_upload_dir 10:58:13 INFO - Copying logs to upload dir... 10:58:13 INFO - mkdir: /builds/slave/test/build/upload/logs 10:58:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1683.586178 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 3 secs) (at 2016-08-26 10:58:13.303970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 10:58:13.308288) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edfd1020e022434b13e883565840b4cfda5ffec0c18f048f92fcc74338752cbfc36b7deafb51a4142930ff33a4cd859691ff7b17b8bffa0961ec408461c21ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee52fcb6646b6e78216e6e821a86dc2f7f4d7bc833123252af9342bae5af42b9bbbcf1ff55132fe4a8c401cd8be4bf87327eccb8832fe7063a7812ea05e6d52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d2e36f9afcac0fdbf749ccbf558823667f01a49d95ddf3ea975101245df7351f12099a3cc4621ade938c4f4c424117980bcd09a2563f7042632afc961faacc"} build_url:https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011437 build_url: 'https://queue.taskcluster.net/v1/task/RoDNL8qSSOu9RJ7pGfOWzQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edfd1020e022434b13e883565840b4cfda5ffec0c18f048f92fcc74338752cbfc36b7deafb51a4142930ff33a4cd859691ff7b17b8bffa0961ec408461c21ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee52fcb6646b6e78216e6e821a86dc2f7f4d7bc833123252af9342bae5af42b9bbbcf1ff55132fe4a8c401cd8be4bf87327eccb8832fe7063a7812ea05e6d52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d2e36f9afcac0fdbf749ccbf558823667f01a49d95ddf3ea975101245df7351f12099a3cc4621ade938c4f4c424117980bcd09a2563f7042632afc961faacc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 10:58:13.336993) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:58:13.337548) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472232607.756734-48849184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:58:13.418007) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 10:58:13.418401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 10:58:13.418757) ========= ========= Total master_lag: 0.18 =========