builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-043 starttime: 1473682377.62 results: success (0) buildid: 20160910055116 builduid: fb98bf9a800847589b1767e37e53bef3 revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.632085) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.632578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.632882) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.646655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.646968) ========= 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=d2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 05:12:58-- 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% 111M=0s 2016-09-12 05:12:58 (111 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112214 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.786125) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.786397) ========= 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=d2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017328 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.854414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.854725) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --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 8663d41e015f3c9933a08a65c8349b86f2263354 --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=d2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 05:12:58,422 truncating revision to first 12 chars 2016-09-12 05:12:58,422 Setting DEBUG logging. 2016-09-12 05:12:58,422 attempt 1/10 2016-09-12 05:12:58,422 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8663d41e015f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 05:12:58,767 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.724958 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.616135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.616473) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.616984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.617254) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.627883) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-12 05:12:58.628214) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:12:59 INFO - MultiFileLogger online at 20160912 05:12:59 in /builds/slave/test 05:12:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:12:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:59 INFO - {'append_to_log': False, 05:12:59 INFO - 'base_work_dir': '/builds/slave/test', 05:12:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:12:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:59 INFO - 'branch': 'Mozilla-Aurora', 05:12:59 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:59 INFO - 'config_files': ('talos/linux_config.py',), 05:12:59 INFO - 'default_actions': ('clobber', 05:12:59 INFO - 'read-buildbot-config', 05:12:59 INFO - 'download-and-extract', 05:12:59 INFO - 'populate-webroot', 05:12:59 INFO - 'create-virtualenv', 05:12:59 INFO - 'install', 05:12:59 INFO - 'run-tests'), 05:12:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:59 INFO - 'download_minidump_stackwalk': True, 05:12:59 INFO - 'download_symbols': 'ondemand', 05:12:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:59 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:59 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:59 INFO - 'installer_path': 'installer.exe', 05:12:59 INFO - 'log_level': 'info', 05:12:59 INFO - 'log_name': 'talos', 05:12:59 INFO - 'log_to_console': True, 05:12:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:12:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:12:59 INFO - 'opt_config_files': (), 05:12:59 INFO - 'pip_index': False, 05:12:59 INFO - 'sps_profile': False, 05:12:59 INFO - 'sps_profile_interval': 0, 05:12:59 INFO - 'suite': 'dromaeojs-e10s', 05:12:59 INFO - 'system_bits': '32', 05:12:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:59 INFO - 'title': 'talos-linux64-ix-043', 05:12:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:59 INFO - 'use_talos_json': True, 05:12:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:59 INFO - 'work_dir': 'build'} 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.265271Z] Running clobber step. 05:12:59 INFO - Running pre-action listener: _resource_record_pre_action 05:12:59 INFO - Running main action method: clobber 05:12:59 INFO - rmtree: /builds/slave/test/build 05:12:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:59 INFO - Running post-action listener: _resource_record_post_action 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.720446Z] Finished clobber step (success) 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.720546Z] Running read-buildbot-config step. 05:12:59 INFO - Running pre-action listener: _resource_record_pre_action 05:12:59 INFO - Running main action method: read_buildbot_config 05:12:59 INFO - Using buildbot properties: 05:12:59 INFO - { 05:12:59 INFO - "project": "", 05:12:59 INFO - "product": "firefox", 05:12:59 INFO - "script_repo_revision": "production", 05:12:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 05:12:59 INFO - "repository": "", 05:12:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 05:12:59 INFO - "buildid": "20160910055116", 05:12:59 INFO - "pgo_build": "True", 05:12:59 INFO - "basedir": "/builds/slave/test", 05:12:59 INFO - "buildnumber": 550, 05:12:59 INFO - "slavename": "talos-linux64-ix-043", 05:12:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:12:59 INFO - "platform": "ubuntu64_hw", 05:12:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:12:59 INFO - "branch": "mozilla-aurora", 05:12:59 INFO - "repo_path": "releases/mozilla-aurora", 05:12:59 INFO - "revision": "8663d41e015f3c9933a08a65c8349b86f2263354", 05:12:59 INFO - "stage_platform": "linux64-pgo", 05:12:59 INFO - "builduid": "fb98bf9a800847589b1767e37e53bef3", 05:12:59 INFO - "slavebuilddir": "test" 05:12:59 INFO - } 05:12:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 05:12:59 INFO - Running post-action listener: _resource_record_post_action 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.723610Z] Finished read-buildbot-config step (success) 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.723700Z] Running download-and-extract step. 05:12:59 INFO - Running pre-action listener: _resource_record_pre_action 05:12:59 INFO - Running main action method: download_and_extract 05:12:59 INFO - mkdir: /builds/slave/test/build/tests 05:12: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')]} 05:12:59 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:12:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:12:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:12:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:12:59 INFO - retry: Failed, sleeping 30 seconds before retrying 05:13:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:13:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:13:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:13:29 INFO - retry: Failed, sleeping 60 seconds before retrying 05:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:14:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:14:29 INFO - Caught exception: HTTP Error 404: Not Found 05:14:29 INFO - Caught exception: HTTP Error 404: Not Found 05:14:29 INFO - Caught exception: HTTP Error 404: Not Found 05:14:29 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:30 INFO - Downloaded 1361 bytes. 05:14:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:30 INFO - Using the following test package requirements: 05:14:30 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:30 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:14:30 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:14:30 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'jsshell-linux-x86_64.zip'], 05:14:30 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:14:30 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:30 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:14:30 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:14:30 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:14:30 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:14:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:30 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:14:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:14:30 INFO - retry: Failed, sleeping 30 seconds before retrying 05:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:15:00 INFO - retry: Failed, sleeping 60 seconds before retrying 05:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:16:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:16:00 INFO - Caught exception: HTTP Error 404: Not Found 05:16:00 INFO - Caught exception: HTTP Error 404: Not Found 05:16:00 INFO - Caught exception: HTTP Error 404: Not Found 05:16:00 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:01 INFO - Downloaded 21770904 bytes. 05:16:01 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'] 05:16:01 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 05:16:02 INFO - Return code: 0 05:16:02 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 05:16:02 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')]} 05:16:02 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:16:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:16:02 INFO - retry: Failed, sleeping 30 seconds before retrying 05:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:16:32 INFO - retry: Failed, sleeping 60 seconds before retrying 05:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:17:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:17:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:17:32 INFO - Caught exception: HTTP Error 404: Not Found 05:17:32 INFO - Caught exception: HTTP Error 404: Not Found 05:17:32 INFO - Caught exception: HTTP Error 404: Not Found 05:17:32 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:17:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:33 INFO - Downloaded 21770904 bytes. 05:17:33 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'] 05:17:33 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 05:17:34 INFO - Return code: 0 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:17:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:17:34 INFO - retry: Failed, sleeping 30 seconds before retrying 05:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:18:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:18:04 INFO - retry: Failed, sleeping 60 seconds before retrying 05:19:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:19:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:19:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:19:05 INFO - Caught exception: HTTP Error 404: Not Found 05:19:05 INFO - Caught exception: HTTP Error 404: Not Found 05:19:05 INFO - Caught exception: HTTP Error 404: Not Found 05:19:05 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:19:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:05 INFO - Downloaded 14413740 bytes. 05:19:05 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'] 05:19:05 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 05:19:06 INFO - Return code: 0 05:19: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')]} 05:19:06 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:19:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:19:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:19:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:19:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:19:06 INFO - retry: Failed, sleeping 30 seconds before retrying 05:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:19:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:19:36 INFO - retry: Failed, sleeping 60 seconds before retrying 05:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:20:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:20:36 INFO - Caught exception: HTTP Error 404: Not Found 05:20:36 INFO - Caught exception: HTTP Error 404: Not Found 05:20:36 INFO - Caught exception: HTTP Error 404: Not Found 05:20:36 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:37 INFO - Downloaded 63895992 bytes. 05:20:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:37 INFO - mkdir: /builds/slave/test/properties 05:20:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:37 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:37 INFO - Contents: 05:20:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:38 INFO - Running post-action listener: _resource_record_post_action 05:20:38 INFO - Running post-action listener: set_extra_try_arguments 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.049437Z] Finished download-and-extract step (success) 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.049670Z] Running populate-webroot step. 05:20:38 INFO - Running pre-action listener: _resource_record_pre_action 05:20:38 INFO - Running main action method: populate_webroot 05:20:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:38 INFO - '--tppagecycles', 05:20:38 INFO - '7'], 05:20:38 INFO - 'tests': ['tsvgm']}}, 05:20:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['damp', 'tps']}, 05:20:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'tests': ['damp', 'tps']}, 05:20:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['dromaeo_dom']}, 05:20:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['basic_compositor_video']}, 05:20:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tsvgx', 05:20:38 INFO - 'tsvgr_opacity', 05:20:38 INFO - 'tart', 05:20:38 INFO - 'tscrollx', 05:20:38 INFO - 'cart']}, 05:20:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:38 INFO - 'tsvgr_opacity', 05:20:38 INFO - 'tart', 05:20:38 INFO - 'tscrollx', 05:20:38 INFO - 'cart']}, 05:20:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tp5o']}, 05:20:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'tests': ['tp5o']}, 05:20:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s', 05:20:38 INFO - '--xperf_path', 05:20:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:38 INFO - 'tests': ['tp5n']}, 05:20:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--xperf_path', 05:20:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:38 INFO - 'tests': ['tp5n']}}, 05:20:38 INFO - 'talos.zip': {'path': '', 05:20:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:38 INFO - Running post-action listener: _resource_record_post_action 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.069891Z] Finished populate-webroot step (success) 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.069979Z] Running create-virtualenv step. 05:20:38 INFO - Running pre-action listener: _resource_record_pre_action 05:20:38 INFO - Running main action method: create_virtualenv 05:20:38 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:38 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 05:20:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:38 INFO - Using real prefix '/usr' 05:20:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:40 INFO - Installing pip.................done. 05:20:40 INFO - Return code: 0 05:20:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:40 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')]} 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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')]} 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:40 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 05:20:40 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 05:20:40 INFO - Using env: {'DISPLAY': ':0', 05:20:40 INFO - 'HOME': '/home/cltbld', 05:20:40 INFO - 'LANG': 'en_US.UTF-8', 05:20:40 INFO - 'LANGUAGE': 'en_US:en', 05:20:40 INFO - 'LOGNAME': 'cltbld', 05:20:40 INFO - 'MAIL': '/var/mail/cltbld', 05:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:40 INFO - 'MOZ_NO_REMOTE': '1', 05:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:40 INFO - 'NO_EM_RESTART': '1', 05:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:40 INFO - 'PWD': '/builds/slave/test', 05:20:40 INFO - 'SHELL': '/bin/bash', 05:20:40 INFO - 'SHLVL': '1', 05:20:40 INFO - 'TERM': 'linux', 05:20:40 INFO - 'TMOUT': '86400', 05:20:40 INFO - 'USER': 'cltbld', 05:20:40 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:40 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 05:20:40 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:40 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')]} 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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')]} 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:40 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 05:20:40 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 05:20:40 INFO - Using env: {'DISPLAY': ':0', 05:20:40 INFO - 'HOME': '/home/cltbld', 05:20:40 INFO - 'LANG': 'en_US.UTF-8', 05:20:40 INFO - 'LANGUAGE': 'en_US:en', 05:20:40 INFO - 'LOGNAME': 'cltbld', 05:20:40 INFO - 'MAIL': '/var/mail/cltbld', 05:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:40 INFO - 'MOZ_NO_REMOTE': '1', 05:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:40 INFO - 'NO_EM_RESTART': '1', 05:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:40 INFO - 'PWD': '/builds/slave/test', 05:20:40 INFO - 'SHELL': '/bin/bash', 05:20:40 INFO - 'SHLVL': '1', 05:20:40 INFO - 'TERM': 'linux', 05:20:40 INFO - 'TMOUT': '86400', 05:20:40 INFO - 'USER': 'cltbld', 05:20:40 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:41 INFO - Downloading/unpacking psutil>=3.1.1 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:43 INFO - Installing collected packages: psutil 05:20:43 INFO - Running setup.py install for psutil 05:20:43 INFO - building 'psutil._psutil_linux' extension 05:20:43 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 05:20:43 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 05:20:43 INFO - building 'psutil._psutil_posix' extension 05:20:43 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 05:20:43 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 05:20:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:43 INFO - Successfully installed psutil 05:20:43 INFO - Cleaning up... 05:20:43 INFO - Return code: 0 05:20:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:43 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')]} 05:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:43 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')]} 05:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:43 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:43 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 05:20:43 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 05:20:43 INFO - Using env: {'DISPLAY': ':0', 05:20:43 INFO - 'HOME': '/home/cltbld', 05:20:43 INFO - 'LANG': 'en_US.UTF-8', 05:20:43 INFO - 'LANGUAGE': 'en_US:en', 05:20:43 INFO - 'LOGNAME': 'cltbld', 05:20:43 INFO - 'MAIL': '/var/mail/cltbld', 05:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:43 INFO - 'MOZ_NO_REMOTE': '1', 05:20:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:43 INFO - 'NO_EM_RESTART': '1', 05:20:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:43 INFO - 'PWD': '/builds/slave/test', 05:20:43 INFO - 'SHELL': '/bin/bash', 05:20:43 INFO - 'SHLVL': '1', 05:20:43 INFO - 'TERM': 'linux', 05:20:43 INFO - 'TMOUT': '86400', 05:20:43 INFO - 'USER': 'cltbld', 05:20:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:43 INFO - '_': '/tools/buildbot/bin/python'} 05:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:43 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 05:20:43 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 05:20:43 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 05:20:43 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 05:20:43 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 05:20:43 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 05:20:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:20:45 INFO - Installing collected packages: mozsystemmonitor 05:20:45 INFO - Running setup.py install for mozsystemmonitor 05:20:45 INFO - Successfully installed mozsystemmonitor 05:20:45 INFO - Cleaning up... 05:20:45 INFO - Return code: 0 05:20:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:45 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:45 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 05:20:45 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 05:20:45 INFO - Using env: {'DISPLAY': ':0', 05:20:45 INFO - 'HOME': '/home/cltbld', 05:20:45 INFO - 'LANG': 'en_US.UTF-8', 05:20:45 INFO - 'LANGUAGE': 'en_US:en', 05:20:45 INFO - 'LOGNAME': 'cltbld', 05:20:45 INFO - 'MAIL': '/var/mail/cltbld', 05:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:45 INFO - 'MOZ_NO_REMOTE': '1', 05:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:45 INFO - 'NO_EM_RESTART': '1', 05:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:45 INFO - 'PWD': '/builds/slave/test', 05:20:45 INFO - 'SHELL': '/bin/bash', 05:20:45 INFO - 'SHLVL': '1', 05:20:45 INFO - 'TERM': 'linux', 05:20:45 INFO - 'TMOUT': '86400', 05:20:45 INFO - 'USER': 'cltbld', 05:20:45 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:45 INFO - '_': '/tools/buildbot/bin/python'} 05:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:46 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:48 INFO - warning: no files found matching '*.txt' 05:20:48 INFO - no previously-included directories found matching 'build' 05:20:48 INFO - no previously-included directories found matching 'dist' 05:20:48 INFO - no previously-included directories found matching '.git*' 05:20:48 INFO - Installing collected packages: jsonschema, functools32 05:20:48 INFO - Running setup.py install for functools32 05:20:48 INFO - warning: no files found matching '*.txt' 05:20:48 INFO - no previously-included directories found matching 'build' 05:20:48 INFO - no previously-included directories found matching 'dist' 05:20:48 INFO - no previously-included directories found matching '.git*' 05:20:48 INFO - Successfully installed jsonschema functools32 05:20:48 INFO - Cleaning up... 05:20:48 INFO - Return code: 0 05:20:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20:48 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')]} 05:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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')]} 05:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:48 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 05:20:48 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 05:20:48 INFO - Using env: {'DISPLAY': ':0', 05:20:48 INFO - 'HOME': '/home/cltbld', 05:20:48 INFO - 'LANG': 'en_US.UTF-8', 05:20:48 INFO - 'LANGUAGE': 'en_US:en', 05:20:48 INFO - 'LOGNAME': 'cltbld', 05:20:48 INFO - 'MAIL': '/var/mail/cltbld', 05:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:48 INFO - 'MOZ_NO_REMOTE': '1', 05:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:48 INFO - 'NO_EM_RESTART': '1', 05:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:48 INFO - 'PWD': '/builds/slave/test', 05:20:48 INFO - 'SHELL': '/bin/bash', 05:20:48 INFO - 'SHLVL': '1', 05:20:48 INFO - 'TERM': 'linux', 05:20:48 INFO - 'TMOUT': '86400', 05:20:48 INFO - 'USER': 'cltbld', 05:20:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:48 INFO - '_': '/tools/buildbot/bin/python'} 05:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:48 INFO - Cleaning up... 05:20:48 INFO - Return code: 0 05:20:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:48 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')]} 05:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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')]} 05:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:48 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 05:20:48 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 05:20:48 INFO - Using env: {'DISPLAY': ':0', 05:20:48 INFO - 'HOME': '/home/cltbld', 05:20:48 INFO - 'LANG': 'en_US.UTF-8', 05:20:48 INFO - 'LANGUAGE': 'en_US:en', 05:20:48 INFO - 'LOGNAME': 'cltbld', 05:20:48 INFO - 'MAIL': '/var/mail/cltbld', 05:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:48 INFO - 'MOZ_NO_REMOTE': '1', 05:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:48 INFO - 'NO_EM_RESTART': '1', 05:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:48 INFO - 'PWD': '/builds/slave/test', 05:20:48 INFO - 'SHELL': '/bin/bash', 05:20:48 INFO - 'SHLVL': '1', 05:20:48 INFO - 'TERM': 'linux', 05:20:48 INFO - 'TMOUT': '86400', 05:20:48 INFO - 'USER': 'cltbld', 05:20:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:48 INFO - '_': '/tools/buildbot/bin/python'} 05:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:48 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 05:20:50 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:50 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 05:20:50 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 05:20:50 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 05:20:50 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 05:20:50 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 05:20:50 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 05:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 INFO - Downloading docopt-0.6.1.tar.gz 05:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:51 INFO - Running setup.py install for blobuploader 05:20:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:51 INFO - Running setup.py install for requests 05:20:52 INFO - Running setup.py install for docopt 05:20:52 INFO - Successfully installed blobuploader requests docopt 05:20:52 INFO - Cleaning up... 05:20:52 INFO - Return code: 0 05:20:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:52 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')]} 05:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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')]} 05:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:52 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 05:20:52 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 05:20:52 INFO - Using env: {'DISPLAY': ':0', 05:20:52 INFO - 'HOME': '/home/cltbld', 05:20:52 INFO - 'LANG': 'en_US.UTF-8', 05:20:52 INFO - 'LANGUAGE': 'en_US:en', 05:20:52 INFO - 'LOGNAME': 'cltbld', 05:20:52 INFO - 'MAIL': '/var/mail/cltbld', 05:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:52 INFO - 'MOZ_NO_REMOTE': '1', 05:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:52 INFO - 'NO_EM_RESTART': '1', 05:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:52 INFO - 'PWD': '/builds/slave/test', 05:20:52 INFO - 'SHELL': '/bin/bash', 05:20:52 INFO - 'SHLVL': '1', 05:20:52 INFO - 'TERM': 'linux', 05:20:52 INFO - 'TMOUT': '86400', 05:20:52 INFO - 'USER': 'cltbld', 05:20:52 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:52 INFO - '_': '/tools/buildbot/bin/python'} 05:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:52 INFO - Running setup.py (path:/tmp/pip-4alhuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:52 INFO - Running setup.py (path:/tmp/pip-9cmLcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:52 INFO - Running setup.py (path:/tmp/pip-tEsPpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:52 INFO - Running setup.py (path:/tmp/pip-Xrzio_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:52 INFO - Running setup.py (path:/tmp/pip-3wzPRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:52 INFO - Running setup.py (path:/tmp/pip-Fnbp5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:52 INFO - Running setup.py (path:/tmp/pip-Caxv9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:52 INFO - Running setup.py (path:/tmp/pip-2AjpAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:52 INFO - Running setup.py (path:/tmp/pip-Pg9WYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:53 INFO - Running setup.py (path:/tmp/pip-tZtorH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:53 INFO - Running setup.py (path:/tmp/pip-EPuPbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:53 INFO - Running setup.py (path:/tmp/pip-kXicGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:53 INFO - Running setup.py (path:/tmp/pip-TqvWVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:53 INFO - Running setup.py (path:/tmp/pip-HhCIc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:53 INFO - Running setup.py (path:/tmp/pip-SO9gQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:53 INFO - Running setup.py (path:/tmp/pip-zXveyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:53 INFO - Running setup.py (path:/tmp/pip-VH2mum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:53 INFO - Running setup.py install for manifestparser 05:20:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:53 INFO - Running setup.py install for mozcrash 05:20:53 INFO - Running setup.py install for mozdebug 05:20:53 INFO - Running setup.py install for mozdevice 05:20:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozfile 05:20:54 INFO - Running setup.py install for mozhttpd 05:20:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozinfo 05:20:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozInstall 05:20:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozleak 05:20:54 INFO - Running setup.py install for mozlog 05:20:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for moznetwork 05:20:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozprocess 05:20:54 INFO - Running setup.py install for mozprofile 05:20:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozrunner 05:20:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Running setup.py install for mozscreenshot 05:20:55 INFO - Running setup.py install for moztest 05:20:55 INFO - Running setup.py install for mozversion 05:20:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:55 INFO - Cleaning up... 05:20:55 INFO - Return code: 0 05:20:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:55 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')]} 05:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:55 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')]} 05:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:55 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:55 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 05:20:55 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 05:20:55 INFO - Using env: {'DISPLAY': ':0', 05:20:55 INFO - 'HOME': '/home/cltbld', 05:20:55 INFO - 'LANG': 'en_US.UTF-8', 05:20:55 INFO - 'LANGUAGE': 'en_US:en', 05:20:55 INFO - 'LOGNAME': 'cltbld', 05:20:55 INFO - 'MAIL': '/var/mail/cltbld', 05:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:55 INFO - 'MOZ_NO_REMOTE': '1', 05:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:55 INFO - 'NO_EM_RESTART': '1', 05:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:55 INFO - 'PWD': '/builds/slave/test', 05:20:55 INFO - 'SHELL': '/bin/bash', 05:20:55 INFO - 'SHLVL': '1', 05:20:55 INFO - 'TERM': 'linux', 05:20:55 INFO - 'TMOUT': '86400', 05:20:55 INFO - 'USER': 'cltbld', 05:20:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:55 INFO - '_': '/tools/buildbot/bin/python'} 05:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:55 INFO - Running setup.py (path:/tmp/pip-3ZzH7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:55 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)) 05:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:55 INFO - Running setup.py (path:/tmp/pip-gUI_XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:55 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)) 05:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:55 INFO - Running setup.py (path:/tmp/pip-oM32KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:55 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)) 05:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:55 INFO - Running setup.py (path:/tmp/pip-zx2ssm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:55 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)) 05:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:55 INFO - Running setup.py (path:/tmp/pip-8db4A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:56 INFO - Running setup.py (path:/tmp/pip-x5mH6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:56 INFO - Running setup.py (path:/tmp/pip-UVHPaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:56 INFO - Running setup.py (path:/tmp/pip-I6ut3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:56 INFO - Running setup.py (path:/tmp/pip-NXMIyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:56 INFO - Running setup.py (path:/tmp/pip-pv06Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:56 INFO - Running setup.py (path:/tmp/pip-nbWYnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:56 INFO - Running setup.py (path:/tmp/pip-CbbfMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:56 INFO - Running setup.py (path:/tmp/pip-ZltLUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:56 INFO - Running setup.py (path:/tmp/pip-RyTk0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:56 INFO - Running setup.py (path:/tmp/pip-6cYkz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:56 INFO - Running setup.py (path:/tmp/pip-cWlvWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:56 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)) 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:56 INFO - Running setup.py (path:/tmp/pip-2A6Caw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 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)) 05:20:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:58 INFO - Downloading blessings-1.6.tar.gz 05:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:58 INFO - Installing collected packages: blessings 05:20:58 INFO - Running setup.py install for blessings 05:20:58 INFO - Successfully installed blessings 05:20:58 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:59 INFO - Reading from file tmpfile_stdout 05:20:59 INFO - Current package versions: 05:20:59 INFO - argparse == 1.2.1 05:20:59 INFO - blessings == 1.6 05:20:59 INFO - blobuploader == 1.2.4 05:20:59 INFO - docopt == 0.6.1 05:20:59 INFO - functools32 == 3.2.3-2 05:20:59 INFO - jsonschema == 2.5.1 05:20:59 INFO - manifestparser == 1.1 05:20:59 INFO - mozInstall == 1.12 05:20:59 INFO - mozcrash == 0.17 05:20:59 INFO - mozdebug == 0.1 05:20:59 INFO - mozdevice == 0.48 05:20:59 INFO - mozfile == 1.2 05:20:59 INFO - mozhttpd == 0.7 05:20:59 INFO - mozinfo == 0.9 05:20:59 INFO - mozleak == 0.1 05:20:59 INFO - mozlog == 3.3 05:20:59 INFO - moznetwork == 0.27 05:20:59 INFO - mozprocess == 0.23 05:20:59 INFO - mozprofile == 0.28 05:20:59 INFO - mozrunner == 6.12 05:20:59 INFO - mozscreenshot == 0.1 05:20:59 INFO - mozsystemmonitor == 0.3 05:20:59 INFO - moztest == 0.7 05:20:59 INFO - mozversion == 1.4 05:20:59 INFO - psutil == 3.1.1 05:20:59 INFO - requests == 1.2.3 05:20:59 INFO - wsgiref == 0.1.2 05:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 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 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20:59 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 05:20:59 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 05:20:59 INFO - Using env: {'DISPLAY': ':0', 05:20:59 INFO - 'HOME': '/home/cltbld', 05:20:59 INFO - 'LANG': 'en_US.UTF-8', 05:20:59 INFO - 'LANGUAGE': 'en_US:en', 05:20:59 INFO - 'LOGNAME': 'cltbld', 05:20:59 INFO - 'MAIL': '/var/mail/cltbld', 05:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:59 INFO - 'MOZ_NO_REMOTE': '1', 05:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:59 INFO - 'NO_EM_RESTART': '1', 05:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:59 INFO - 'PWD': '/builds/slave/test', 05:20:59 INFO - 'SHELL': '/bin/bash', 05:20:59 INFO - 'SHLVL': '1', 05:20:59 INFO - 'TERM': 'linux', 05:20:59 INFO - 'TMOUT': '86400', 05:20:59 INFO - 'USER': 'cltbld', 05:20:59 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:59 INFO - '_': '/tools/buildbot/bin/python'} 05:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 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)) 05:20:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, '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': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', '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 05:20: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', 'jsonschema'] in /builds/slave/test/build 05:20: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 jsonschema 05:20:59 INFO - Using env: {'DISPLAY': ':0', 05:20:59 INFO - 'HOME': '/home/cltbld', 05:20:59 INFO - 'LANG': 'en_US.UTF-8', 05:20:59 INFO - 'LANGUAGE': 'en_US:en', 05:20:59 INFO - 'LOGNAME': 'cltbld', 05:20:59 INFO - 'MAIL': '/var/mail/cltbld', 05:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:59 INFO - 'MOZ_NO_REMOTE': '1', 05:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:59 INFO - 'NO_EM_RESTART': '1', 05:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:59 INFO - 'PWD': '/builds/slave/test', 05:20:59 INFO - 'SHELL': '/bin/bash', 05:20:59 INFO - 'SHLVL': '1', 05:20:59 INFO - 'TERM': 'linux', 05:20:59 INFO - 'TMOUT': '86400', 05:20:59 INFO - 'USER': 'cltbld', 05:20:59 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:59 INFO - '_': '/tools/buildbot/bin/python'} 05:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Running post-action listener: _resource_record_post_action 05:20:59 INFO - Running post-action listener: _start_resource_monitoring 05:20:59 INFO - Starting resource monitoring. 05:20:59 INFO - [mozharness: 2016-09-12 12:20:59.687957Z] Finished create-virtualenv step (success) 05:20:59 INFO - [mozharness: 2016-09-12 12:20:59.688386Z] Running install step. 05:20:59 INFO - Running pre-action listener: _resource_record_pre_action 05:20:59 INFO - Running main action method: install 05:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:59 INFO - Reading from file tmpfile_stdout 05:20:59 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:59 INFO - Reading from file tmpfile_stdout 05:20:59 INFO - Output received: 05:20:59 INFO - Usage: mozinstall [options] installer 05:20:59 INFO - Options: 05:20:59 INFO - -h, --help show this help message and exit 05:20:59 INFO - -d DEST, --destination=DEST 05:20:59 INFO - Directory to install application into. [default: 05:20:59 INFO - "/builds/slave/test"] 05:20:59 INFO - --app=APP Application being installed. [default: firefox] 05:20:59 INFO - mkdir: /builds/slave/test/build/application 05:20:59 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'] 05:20:59 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 05:21:16 INFO - Reading from file tmpfile_stdout 05:21:16 INFO - Output received: 05:21:16 INFO - /builds/slave/test/build/application/firefox/firefox 05:21:16 INFO - Running post-action listener: _resource_record_post_action 05:21:16 INFO - [mozharness: 2016-09-12 12:21:16.008214Z] Finished install step (success) 05:21:16 INFO - [mozharness: 2016-09-12 12:21:16.008493Z] Running run-tests step. 05:21:16 INFO - Running pre-action listener: _resource_record_pre_action 05:21:16 INFO - Running main action method: run_tests 05:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:16 INFO - Python 2.7.3 05:21:16 INFO - Return code: 0 05:21:16 INFO - grabbing minidump binary from tooltool 05:21: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')]} 05:21:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2176570>, '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 0x2177470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2177900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:21:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:21:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:21:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:16 INFO - Return code: 0 05:21:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:21:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:21:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:21:16 INFO - Using env: {'DISPLAY': ':0', 05:21:16 INFO - 'HOME': '/home/cltbld', 05:21:16 INFO - 'LANG': 'en_US.UTF-8', 05:21:16 INFO - 'LANGUAGE': 'en_US:en', 05:21:16 INFO - 'LOGNAME': 'cltbld', 05:21:16 INFO - 'MAIL': '/var/mail/cltbld', 05:21:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:16 INFO - 'MOZ_NO_REMOTE': '1', 05:21:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:16 INFO - 'NO_EM_RESTART': '1', 05:21:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:16 INFO - 'PWD': '/builds/slave/test', 05:21:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:16 INFO - 'SHELL': '/bin/bash', 05:21:16 INFO - 'SHLVL': '1', 05:21:16 INFO - 'TERM': 'linux', 05:21:16 INFO - 'TMOUT': '86400', 05:21:16 INFO - 'USER': 'cltbld', 05:21:16 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282', 05:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:16 INFO - '_': '/tools/buildbot/bin/python'} 05:21:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:21:16 INFO - mozversion application_buildid: 20160910055116 05:21:16 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:21:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:21:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:16 INFO - mozversion application_name: Firefox 05:21:16 INFO - mozversion application_remotingname: firefox-dev 05:21:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:16 INFO - mozversion application_vendor: Mozilla 05:21:16 INFO - mozversion application_version: 50.0a2 05:21:16 INFO - mozversion platform_buildid: 20160910055116 05:21:16 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:21:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:16 INFO - mozversion platform_version: 50.0a2 05:21:16 INFO - using testdate: 1473682876 05:21:16 INFO - actual date: 1473682876 05:21:16 INFO - starting webserver on 'localhost:53065' 05:21:16 INFO - SUITE-START | Running 2 tests 05:21:16 INFO - TEST-START | dromaeo_css 05:21:16 INFO - operating with platform_type : linux_ 05:21:16 INFO - Initialising browser for dromaeo_css test... 05:21:16 INFO - TEST-INFO | started process 32324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9s5Q2W/profile http://localhost:53065/getInfo.html) 05:21:20 INFO - PROCESS | 32324 | __metrics Screen width/height:1600/1200 05:21:20 INFO - PROCESS | 32324 | colorDepth:24 05:21:20 INFO - PROCESS | 32324 | Browser inner width/height: 1024/697 05:21:20 INFO - PROCESS | 32324 | __metrics 05:21:21 ERROR - PROCESS | 32324 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:21:22 INFO - TEST-INFO | 32324: exit 0 05:21:22 INFO - Browser initialized. 05:21:22 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:22 INFO - TEST-INFO | started process 32451 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9s5Q2W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:34 INFO - PROCESS | 32451 | RSS: Main: 153251840 05:21:34 INFO - PROCESS | 32451 | 05:22:23 INFO - PROCESS | 32451 | 1473682943953 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] 05:24:32 INFO - PROCESS | 32451 | Cycle 1(1): loaded http://localhost:53065/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53065/tests/dromaeo/cssquery-ext.html) 05:24:33 INFO - PROCESS | 32451 | RSS: Main: 159244288 05:24:33 INFO - PROCESS | 32451 | 05:27:35 INFO - PROCESS | 32451 | Cycle 1(1): loaded http://localhost:53065/tests/dromaeo/cssquery-ext.html (next: http://localhost:53065/tests/dromaeo/cssquery-jquery.html) 05:27:36 INFO - PROCESS | 32451 | RSS: Main: 155455488 05:27:36 INFO - PROCESS | 32451 | 05:30:38 INFO - PROCESS | 32451 | Cycle 1(1): loaded http://localhost:53065/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53065/tests/dromaeo/cssquery-mootools.html) 05:30:38 INFO - PROCESS | 32451 | RSS: Main: 156241920 05:30:38 INFO - PROCESS | 32451 | 05:33:40 INFO - PROCESS | 32451 | Cycle 1(1): loaded http://localhost:53065/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53065/tests/dromaeo/cssquery-prototype.html) 05:33:41 INFO - PROCESS | 32451 | RSS: Main: 154267648 05:33:41 INFO - PROCESS | 32451 | 05:36:43 INFO - PROCESS | 32451 | Cycle 1(1): loaded http://localhost:53065/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53065/tests/dromaeo/cssquery-yui.html) 05:36:44 INFO - PROCESS | 32451 | RSS: Main: 156860416 05:36:44 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | Cycle 1(1): loaded http://localhost:53065/tests/dromaeo/cssquery-yui.html (next: http://localhost:53065/tests/dromaeo/cssquery-dojo.html) 05:39:46 INFO - PROCESS | 32451 | RSS: Main: 157593600 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | __start_tp_report 05:39:46 INFO - PROCESS | 32451 | _x_x_mozilla_page_load 05:39:46 INFO - PROCESS | 32451 | _x_x_mozilla_page_load_details 05:39:46 INFO - PROCESS | 32451 | |i|pagename|runs| 05:39:46 INFO - PROCESS | 32451 | |0;dojo.html;3285.5564498792664;3289.243474000981;3310.4372256716356;3314.8508317125725;3328.766986310959;5602.747876345564;5708.11524213747;5715;5735.311762588495;5761.1358296255585;5754.251947246852;5787.594868359217;5788.884222315552;5807.883842323162;5827.592068555192;5202.3497062867145;5266.7629956652045;5337.2794672719265;5358.544523715489;5360.973195134038;5110.463401342842;5144.1254986652175;5146.74266286686;5157.329547158883;5195.350581177353;5196.8700782480555;5218.373795144588;5229.267902493654;5244.9510097980365;5305.6551324164;5283.022640811443;5291.4708529147165;5294.073537131025;5297.390800058004;5312.734363281859;5030.195168773015;5119.692818430869;5143.27994080829;5196.376434827807;5253.789848406059;5526;5656.349519805234;5681.9431805681825;5732.140178973145;5771.278590176228;5966.955782738047;6001.2498437695285;6009.939900600982;6010.519158467312;6018.6689732064715;6010.3689112643415;6016.458518733335;6020.819375418744;6021.939780602182;6115.575014303848;5678.261825962599;5701.059325211335;5713.74288157034;5751.712414379264;5801.042827933428;6277.24673039229;6279.748810047547;6282.465990390868;6287.465565426898;6287.528435367366;5973.551983601247;6007.038873780174;6031.24609423822;6041.637501749821;6046.244219472566;6137.683438940172;6176.69116544171;6180.598261113013;6269.7178626961895;6270.592411493239;6192.380761923771;6224.813255602339;6266.342034086447;6266.874662506724;6271.216097987752;5913.586048976616;6215.595986260879;6268.498520118471;6283.465905398001;6290.716917738621;4612.838550650711;4625.213713668684;4633;4665.4168228971375;4693.55411235932;4100.36444351126;4141.5237247716295;4146.730462519987;4179.2895207814745;4182.498100227931;5749.683767392834;5798.971005144947;5866.67733274674;5875.588708790429;5880.235569375954;6155.47678447337;6190.226221722285;6212.254529456494;6247.094171345129;6283.151774510474;5966.31387390447;5994.07091900756;6005.669688167192;6016.547993157968;6022.69886505673;5230.66618012412;5386.730663466811;5388.191771234347;5389.865253368619;5398.379186393537;6266.749330026839;6342.20722409708;6350.460210882126;6361.9045714314325;6367.981123020295;6124.785632502841;6136.355682653436;6144.907826382519;6163.506919446523;6175.3824617539685;6178.721957511832;6300.621962682254;6317.052442133717;6341.968290158519;6357.237131544244;5897.439743224387;5977.432143946318;5986.281646202484;6010.338862725184;6024.518038556817;6062.636241825505;6068.302145253352;6083.6349819011;6116.204893363834;6116.26604807426;5850.034744267148;5884.941150588611;5936;5962.25471816023;5973;5898.498627616701;5975.312839023568;5977.701114944322;5994.610350327215;6002.759889604367;5927.614705044159;5934.584579079423;5938.554608404474;5938.732757025858;5944.286685597756;5955.9577074011;5985;6035.758569657164;6045.063015232646;6052.455279024909;5920.911186332122;5924.822255332347;5929.970350148222;5933.703314834327;5974.133750606051 05:39:46 INFO - PROCESS | 32451 | |1;ext.html;579.139977133956;638.0716058135414;662.2549631664377;677.9728810847563;702.8629417263636;40697.18605627886;46200.53799462004;46248.53751462484;46302.999999999956;46539.76730116345;45957.31064034042;46437;46530.53469465304;46557.30164047542;46867;9084.409513381643;9178.54107294636;9202.539873006357;9204.907950920471;9214;9077.95461022694;9119.544022798867;9183.49490778007;9189.540522973857;9216.216621587173;9231.261499080058;9274.30442716799;9294;9297.442153470813;9298.767530811716;9206.447613143233;9206.49364284964;9225.354225204239;9244.676436324764;9299.442033478013;9032;9052.140046695553;9089.091090890857;9109.635614575409;9125.863112053325;43497.78251108756;43519.78240108811;43896.78051609721;43938.34092488615;44269.778651106535;3607.7294202934886;3663.757900936999;3673.2286219893585;3729.589745128034;3755.117547376364;35082.47376289332;35324.293514129575;35337.293254134776;35371.469427958604;35486.64513354859;4662.557057079572;4720.126776546315;4747.406574178228;4755.476897541268;4768.690035147704;269643;271330;271927;272211;272626.6368668164;61841.690791545756;62215.688921555105;62242.68878655578;62266.68866655638;62309.68845155745;38614.42078368827;39322.80338598289;39499.605003950164;41926.371104433456;41946.37080443796;17857.37499187548;18117;18181.363652272372;18204.180811863498;18204.45386638403;14048.929755351362;14091.577252682437;14108.717825643429;14126.293685315693;14142.151470911778;20005.299814506423;20280.391588252376;20326.390208293775;20507.58984820295;20568.280110196072;7683.538987660758;7712.807179820437;7744;7774.416918731118;7856.882146767803;1054.920880933933;1077.6713102503654;1107;1149.7413082056637;1179.8053321202078;32119.357612847612;32270;32495.350092998007;33239.16902077419;33762.66237337644;1159.5158197507205;1160.3328086350214;1172.8959264840194;1195.9037317361415;1206.5354838387284;17075.743863842054;17136.40022599203;17227.82772172287;17239.3966211182;17267;469637;678186;680651;682181;683442;62866.68566657137;63564;63754.362456374845;63940;64021.35978640154;1450.9274536273144;1487.226642146078;1507.660776325318;1519.6276912156593;1525.1459182857636;33621.663783362834;33692.663073369935;34164;34308.313833722685;34414;53473.73263133659;54512.18231726448;54628;54655.72672136614;54701.72649136729;558865;564625;565399;566808;567823;30437.695623044372;30459;30620.31588262578;30632.387352253278;30857.537136943414;49223.50776492333;49547.25679114744;49729.50270497249;49981.75009124931;50076.24885626646;42854.571454285055;43034;43660.56339436565;43951.340729889715;44628;41517;41785.16429671329;42847.35728964006;42941.141177175654;43385;36987.260254794215;41157.58842411658;41222.58777412187;42635.14729705326;43041.569584303754;36006;38278.264331209524;38459;38583;38685.226295474495;29208.415831683673;29611.703882961756;30020.69979300179;30135;30189.849050754605 05:39:46 INFO - PROCESS | 32451 | |2;jquery.html;412.2990915443746;412.6492481390818;426.5457287988292;428.1629414494663;441.93370994350863;18888.90555547222;20752.169913203474;20814.89592552037;21135.154593816267;21678.132874684994;19009.809901900975;20002.69995950062;20181.798182018174;20944.581108377824;21082;5885.67628780418;6097.817065488042;6128.60164089335;6134.4172303631085;6140.84647883802;6002.849928751794;6026.06595977622;6074.453299203071;6086.482648974842;6119;5880.2355693759755;5979.043353063511;6044.697765111749;6053.848653783647;6057.878842423149;5921.674307913085;5984.072468767348;5986.371430999726;5997.6701281429505;6030.4572588467245;5387.488188622075;5396.622236443451;5400.189971504266;5412;5429.728513574306;21790.346289611338;22358.10567577295;24069.51860962771;24745.133920312703;24912.377190570198;2532.1011041080337;2626.9737302627113;2638.934026649349;2655.9336016599545;2679.0891097027106;10425.478726063666;10552.103071238955;11017.44912754359;11382.2032457728;11560.653180404517;2010.1155491583802;2092.299079808251;2105.1263725553877;2114.830813534929;2124.0547956159517;87508;87770;88173;89347.10652893453;91056;11546.538138474367;11697;11732.413379331;11901.464434100486;11991.640250792316;16835.74746378805;16948;16948.152592370578;16953.83046169547;17036.659266814593;8732.825343493094;8783.253423459077;8809.11908809114;8907;8931.821363572692;8507.149285071444;8545.572721363907;8602.87095693565;8728.781780455412;8746.34402419821;5099.4390617032095;5100.0819852426175;5121.206213036985;5153.845384638467;5256.789728410898;2077.979220207779;2108.694239335288;2119.2476670781957;2123.723915890955;2139.7111389962156;931.7018554062635;1058.1640504001907;1066.4294602387686;1077.7736675298274;1078.800421921947;20230.19079236814;20298.188072476936;20377.49056273605;20494.487637809176;20991.790082098985;972.9318947673736;1018.6943916824923;1028.4600584692946;1078.3314345106085;1113.777244551093;10808.459577021118;11479.885201148049;11648.825267620992;11653;11663.533458661559;396379;398637;399476;529721;533120;80848.59575702202;85586;90017;101006.49496752469;101911.4904425473;1018.5874720738113;1041.7083216699184;1059.3273271472517;1061.0874647802884;1064.920130990179;16272.674546508766;18977.43067707971;19215.90392048087;19222;19560.021998899712;44877.326840098074;45257;45905.31142032939;46616.53383466122;46648.533514665774;65816.34183658102;66999;67073.32926670866;67146.66426667836;67872;8890.377673562789;9004.594793234452;9036.909630903607;9092.59083341238;9331;10097.646582369582;13041.282729449971;13326.666833328856;13359.1316564423;13885.930570347084;11598.536058557564;11641.185117041725;11716.06271498261;11829.76340473203;11940.52237910474;11746.471408786796;11763.411829408324;11770.646880593597;11948.701282468008;12338.629841104781;11743.823842642196;12063.457144428347;12169.452374642986;12212.267263964191;12258.75482490363;9071.501067441353;9130.49782261982;9139.086091391073;9149.725508234764;9323.393979391318;9117.452952822852;11923.0461563073;11957.880421195676;12008.579699710468;12059.276443413422 05:39:46 INFO - PROCESS | 32451 | |3;mootools.html;1556.8988015778978;1582.0270533621822;1748.851347635451;1811.248331942244;1858.637565674694;8389.328853691706;8884.733457996263;8900.955495222532;9647.42115473072;9648.662296819613;9002.774930626721;9338.813223735522;9361.391509551875;9423.81152376952;9446.81106377872;2523.40699935515;2529.7976161907004;2535.3915060385466;2557.4629327841176;2558.6034164704406;2360.9645855312183;2482.8386154899963;2515.295717199178;2519.8236123471365;2521.7856482199036;2458.6926634170727;2469.333280014395;2491.5266099441137;2500.249925022495;2505.711843138035;2424.9515009699708;2436.1595249638845;2444.8655323957264;2475.4306509502917;2476.418041760185;2435.768601982809;2458.311672731638;2473.5547601431736;2484;2484.9751502485105;9334.159925606727;9463.242940564738;9477.66828161018;9498.050194980517;9564.426134431887;5443.863903402446;5548.44515548446;5562.110062390038;5573.303337082864;5601.887962240773;3329.816860072695;3400.523926650271;3438.381091403546;3442.431998720208;3443.8450269737923;1175.2067354535739;1237.7276999060193;1282.9422675979604;1283.3390803736083;1303.211557008006;12674.74650506994;12785.360731963458;12860.356982150855;12870.356482175854;12913.096083274175;4823.300621409876;4826.469088400273;4861.635377346643;4892.755362231874;5002.099622068063;10902.563897444172;10920.399378034126;10927.781444371069;10933.726656833644;10936.125109991182;5429.375621803464;5453.100238460689;5466.835994920159;5470.070088085035;5472.452754724575;4490.910181796346;4993.375828021497;5045.344105266366;5074.974625126924;5090.618203634742;4201.411802347674;4245.150969806051;4291.67812414064;4291.806868690916;4336.588024137702;1268.7779638563215;1270.4981532294876;1274.0635632809876;1282.9422675979604;1285.5050805439973;980.4705459052032;1009.8636684047559;1034.6947650443137;1048.9423081730579;1082.6319051522362;6310.716017779211;6390.4248617624635;6446.000869865178;6508.772192973223;6567.67161641916;463.367503357914;491.7492079039715;494.52278551198265;500.14724894055547;500.6345367881467;4354.259775838115;4431.423914891108;4444.488883778342;4453.866384008485;4459.375687403766;11559;11716.824247636292;11840.230385024946;11886.583969561023;11919.344436055926;11088.445577721082;11801.468933897995;11806.586769463027;11816.113791465503;11850;8730;8971.685990990414;8975.910240897769;8980.236679882153;8999.19007289347;6613.900791488035;6652.002199670088;6795.354441328066;6811.046453496416;6831.863362732817;10583.100436462986;10778.892211078102;10874.619388321371;10920.672379828618;10978.121750259801;11083.334999900031;11093.44532773342;11131.220814543229;11175.832362514731;11221.158413118885;8241.711540096068;8275.83448331018;8336.416450848383;8351.498910065415;8408.159184081544;9140.085991400807;9153;9188.98921118663;9194.95402522983;9203.677871274473;7854.882176767467;7910.841783164419;7912.564808935564;7952.36381089523;8044.959775201087;7968.601569921365;7980.561069141253;8005.479643823133;8048.678052877819;8256.25693687571;7399.48203625741;7524.083821630791;7534.962325188339;7555.6977720890545;7577.810554736176;5574.414686458026;5823.4467725566;5881.470667639933;5909.911351329648;5947.137665038459;5651.57613179005;5874;5898.1152827076285;6050.6369617823075;6093.421124993119 05:39:46 INFO - PROCESS | 32451 | |4;prototype.html;352.6931569534505;354.70736642270134;355.301831900316;360.14465644095276;364.4114754671205;5868.090445980874;5900.822975310747;5901.616394934326;5915.9704201478935;5956.940430595692;5540.584456165793;5785.045467497868;5799.449052340032;5804.390538993408;5862.530997520199;4514.164879497295;4523.705959112664;4526.932096018562;4533.365328853946;4540.9772951135365;4543.681942264043;4564.5435456454425;4585.747783871886;4593.471750748673;4602.401687780583;4396.538363471821;4557.225271703818;4559.566841150102;4562.315652652095;4562.863114106565;4499.572540608637;4500.549945005506;4513.368128461985;4517.435320584927;4551.658625603077;3980.203959208141;3990.221906728189;3991.7604943703473;4010.63904248619;4017.1764788218347;5563.304586926634;5722.685252311121;5744.655320680772;5753.913691294634;5809.331926828384;5190.532852043297;5193.68837869729;5207.869803254911;5215.243789650517;5219.739013049332;6094.634321940698;6105.206323178003;6114.480269177126;6122.54080943931;6150.354212807635;4254.808533615995;4383.7150585212175;4397.824087036514;4402.471703395581;4406.6339434775;6078.234403346511;6297.086922396227;6309.968450157766;6310.211223597051;6312.305646378894;4478.596926276586;4488.236999710057;4510.909781804346;4513.526079761577;4519.819207231674;4554.863354099382;4556.635469162459;4586.633069354511;4602.401687780567;4606.700564463299;5002.424721157041;5254.395744489356;5262.71055091966;5269.657472264291;5278.81524146653;6081.118237855575;6103.2230335198265;6113.144159817629;6120.846978825476;6140.17107690456;1553.1690545558072;1570.261976870869;1572.2610373124612;1580.194101008494;1588.1820862255947;965.9565319560637;998.8862418403573;1015.7359086637528;1017.4912543728136;1033.4625994482826;5224.738763061831;5241.54058321506;5271.71005594696;5272.947270527246;5314.468553144655;5565.554755619622;5575.80120274143;5577.525910297589;5598.172922674105;5611.859703507445;4933.87665308363;5195.2986346843445;5205.167173252262;5212.879230965361;5297.973510132501;2935.6477222732983;2982.701729827;3028.4700177468853;3029.2585210384636;3029.3941211757738;5691.093483683013;5768.673205162836;5790.942090579125;5805.548612846788;5831.271091113611;5933.110033495011;6402.423781859655;6414.775482858077;6442.903356449657;6451.580647257913;6493.188351456068;6562.50781191403;6564.507661925375;6568.901466478007;6575.868482630416;6099.786507472217;6286.402791734778;6289.528285378524;6298.496120310456;6320.810375688737;6212.782552610637;6221.44463884029;6249.687515624109;6250.624962502265;6272.92993312215;6241.96879015602;6476.805695829133;6484.416402523795;6536.640484773383;6558.475321974135;4401.471823381215;4428.734275943454;4433.7561434121435;4464.687471876938;4481.865544033684;6254.8123556293385;6342.556021078481;6369.33122022181;6443.356943979255;6447.838804029937;6109.419605137505;6128.63228206309;6136.754529818936;6150.585365366081;6185.474234689922;6124.969375153096;6157.4458298753325;6174.25908890936;6176.598521096114;6188.195534580475;5927.8221653350465;6076.78731244422;6140.478059364826;6150.50795936333;6152.692365381624;6134.2332208473945;6213.59611625261;6223.439890409885;6251.562390632794;6260.937390626035;5813;6040.7583696651645;6045.667488288186;6057.576469529745;6063.151158837854 05:39:46 INFO - PROCESS | 32451 | |5;yui.html;787.4527203593505;821.9136990615985;828.9709860154895;900.7027680865313;921.6221349246802;16448.671026579475;17165.313387464517;17195.74206386902;17462.388816391427;17820;15312.693746125098;15428.768568471482;15432.151231682254;15436.536903892873;15472.458463953764;741.4142827166559;876.8246350729879;890.5814267294394;896.5382827843666;909.8771665825095;898.7258886039755;914.1270087066869;914.1589737441536;914.1772404835634;915.7298596913927;955.3694561589352;956.3974695941582;957.1194501059042;957.530809903146;958.4584709639066;954.4893481987139;955.4076472587005;955.7037318431309;957.1146689312393;957.6887511558701;690.9274526174729;694.6318451220861;694.9687264073078;696.195893742728;697.3828162076549;16296.42962496307;16391.34434622614;16419.42532011386;16461.423850165305;16468.588285292844;16735.163241837858;17177.484675459757;17179.226934787963;17327.133643317782;17721.025343606096;12921.643977082034;13033.608991730263;13480.932595337059;13488;13546.59360219195;4116.403121547359;4135.214309281257;4174.060836311836;4180.080382315886;4191.958080419187;87275.56362218212;87737.56131219366;88098.11901881028;91580;92970.53514732451;13106.2136271824;13284.800727989088;13342.199468031755;13402.53091141825;13566.457341706418;24731.752682473303;24765.380865478506;24768.752312477005;24853.875730621592;25950.610740838903;9055.59249833759;9057.411268267542;9082.500462474494;9087.727368178965;9254.259659227246;5042.84871453857;5187.3658999051795;5237.188235823453;5242.318498595158;5294.894102117936;3701.555813302421;3852.3836186210633;3869.3615553433933;3872.612738726105;3873.1672690371142;612.1368869893429;625.6715224507079;626.4518546272012;634.352959980817;652.7519542573887;735.9116905971317;785.4737325991599;791.6793698552095;801.8516574433748;809.9595020248964;10508.10854046279;10600.25798194119;10828.729281768019;10969.396683182342;11152.665420037412;728.8578727148155;756.3533179131873;760.9771706848803;783.4398405140352;787.8857565653063;4931.876703082452;4940.374787052814;4995.600013920872;5030.547250747377;5048.217526283432;75124.62437687776;98327;106599;127363;131759;66789.66605166942;67016;68079.65960170167;76786;99057.00942990478;93995.53002234946;94367.52816236012;95164;95778.52110739402;97167.02832971721;9017.413868098552;9282.953585232031;9449.291303152153;9463.763405914908;9547.379420337656;54426;54514.72742636262;54878.176827346826;55548.44451555433;56588.43411565832;51676.96646067133;70499;73721.6313918427;74772;76385.61807190928;12612.369381530703;12721.618351449471;12818.230906145662;13001.674958126123;13086.14940028895;14201.786973195616;14203.431862725376;14479.276036198358;14505.274736263356;14628.488002920272;13094.869051309364;13099.738005240033;13241.735165296834;13488.797668035184;13708.65728356799;13069.738605227652;13344.466221351426;13349.332533373099;13443.529476468277;13482.325883705971;12732.554360597334;12773;12841.678958026125;12934.935325323313;13022.739545209231;10744.623938162127;10925.945370273415;11017.614383496539;11044.779104417707;11408.657740267805;10455.79088418212;11226.55093796239;11232.719182020515;11351.829722554003;11519.884801152217 05:39:46 INFO - PROCESS | 32451 | __end_tp_report 05:39:46 INFO - PROCESS | 32451 | __start_cc_report 05:39:46 INFO - PROCESS | 32451 | _x_x_mozilla_cycle_collect,335 05:39:46 INFO - PROCESS | 32451 | __end_cc_report 05:39:46 INFO - PROCESS | 32451 | __startTimestamp1473683986772__endTimestamp 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | ------- Summary: start ------- 05:39:46 INFO - PROCESS | 32451 | Number of tests: 6 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | [#0] dojo.html Cycles:165 Average:5702.25 Median:5938.55 stddev:651.83 (11.0%) stddev-sans-first:625.65 05:39:46 INFO - PROCESS | 32451 | Values: 3285.6 3289.2 3310.4 3314.9 3328.8 5602.7 5708.1 5715.0 5735.3 5761.1 5754.3 5787.6 5788.9 5807.9 5827.6 5202.3 5266.8 5337.3 5358.5 5361.0 5110.5 5144.1 5146.7 5157.3 5195.4 5196.9 5218.4 5229.3 5245.0 5305.7 5283.0 5291.5 5294.1 5297.4 5312.7 5030.2 5119.7 5143.3 5196.4 5253.8 5526.0 5656.3 5681.9 5732.1 5771.3 5967.0 6001.2 6009.9 6010.5 6018.7 6010.4 6016.5 6020.8 6021.9 6115.6 5678.3 5701.1 5713.7 5751.7 5801.0 6277.2 6279.7 6282.5 6287.5 6287.5 5973.6 6007.0 6031.2 6041.6 6046.2 6137.7 6176.7 6180.6 6269.7 6270.6 6192.4 6224.8 6266.3 6266.9 6271.2 5913.6 6215.6 6268.5 6283.5 6290.7 4612.8 4625.2 4633.0 4665.4 4693.6 4100.4 4141.5 4146.7 4179.3 4182.5 5749.7 5799.0 5866.7 5875.6 5880.2 6155.5 6190.2 6212.3 6247.1 6283.2 5966.3 5994.1 6005.7 6016.5 6022.7 5230.7 5386.7 5388.2 5389.9 5398.4 6266.7 6342.2 6350.5 6361.9 6368.0 6124.8 6136.4 6144.9 6163.5 6175.4 6178.7 6300.6 6317.1 6342.0 6357.2 5897.4 5977.4 5986.3 6010.3 6024.5 6062.6 6068.3 6083.6 6116.2 6116.3 5850.0 5884.9 5936.0 5962.3 5973.0 5898.5 5975.3 5977.7 5994.6 6002.8 5927.6 5934.6 5938.6 5938.7 5944.3 5956.0 5985.0 6035.8 6045.1 6052.5 5920.9 5924.8 5930.0 5933.7 5974.1 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | [#1] ext.html Cycles:180 Average:65131.36 Median:43037.78 stddev:138847.80 (322.6%) stddev-sans-first:139152.72 05:39:46 INFO - PROCESS | 32451 | Values: 579.1 638.1 662.3 678.0 702.9 40697.2 46200.5 46248.5 46303.0 46539.8 45957.3 46437.0 46530.5 46557.3 46867.0 9084.4 9178.5 9202.5 9204.9 9214.0 9078.0 9119.5 9183.5 9189.5 9216.2 9231.3 9274.3 9294.0 9297.4 9298.8 9206.4 9206.5 9225.4 9244.7 9299.4 9032.0 9052.1 9089.1 9109.6 9125.9 43497.8 43519.8 43896.8 43938.3 44269.8 3607.7 3663.8 3673.2 3729.6 3755.1 35082.5 35324.3 35337.3 35371.5 35486.6 4662.6 4720.1 4747.4 4755.5 4768.7 269643.0 271330.0 271927.0 272211.0 272626.6 61841.7 62215.7 62242.7 62266.7 62309.7 38614.4 39322.8 39499.6 41926.4 41946.4 17857.4 18117.0 18181.4 18204.2 18204.5 14048.9 14091.6 14108.7 14126.3 14142.2 20005.3 20280.4 20326.4 20507.6 20568.3 7683.5 7712.8 7744.0 7774.4 7856.9 1054.9 1077.7 1107.0 1149.7 1179.8 32119.4 32270.0 32495.4 33239.2 33762.7 1159.5 1160.3 1172.9 1195.9 1206.5 17075.7 17136.4 17227.8 17239.4 17267.0 469637.0 678186.0 680651.0 682181.0 683442.0 62866.7 63564.0 63754.4 63940.0 64021.4 1450.9 1487.2 1507.7 1519.6 1525.1 33621.7 33692.7 34164.0 34308.3 34414.0 53473.7 54512.2 54628.0 54655.7 54701.7 558865.0 564625.0 565399.0 566808.0 567823.0 30437.7 30459.0 30620.3 30632.4 30857.5 49223.5 49547.3 49729.5 49981.8 50076.2 42854.6 43034.0 43660.6 43951.3 44628.0 41517.0 41785.2 42847.4 42941.1 43385.0 36987.3 41157.6 41222.6 42635.1 43041.6 36006.0 38278.3 38459.0 38583.0 38685.2 29208.4 29611.7 30020.7 30135.0 30189.8 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | [#2] jquery.html Cycles:180 Average:28899.28 Median:23213.81 stddev:75691.61 (326.1%) stddev-sans-first:75873.73 05:39:46 INFO - PROCESS | 32451 | Values: 412.3 412.6 426.5 428.2 441.9 18888.9 20752.2 20814.9 21135.2 21678.1 19009.8 20002.7 20181.8 20944.6 21082.0 5885.7 6097.8 6128.6 6134.4 6140.8 6002.8 6026.1 6074.5 6086.5 6119.0 5880.2 5979.0 6044.7 6053.8 6057.9 5921.7 5984.1 5986.4 5997.7 6030.5 5387.5 5396.6 5400.2 5412.0 5429.7 21790.3 22358.1 24069.5 24745.1 24912.4 2532.1 2627.0 2638.9 2655.9 2679.1 10425.5 10552.1 11017.4 11382.2 11560.7 2010.1 2092.3 2105.1 2114.8 2124.1 87508.0 87770.0 88173.0 89347.1 91056.0 11546.5 11697.0 11732.4 11901.5 11991.6 16835.7 16948.0 16948.2 16953.8 17036.7 8732.8 8783.3 8809.1 8907.0 8931.8 8507.1 8545.6 8602.9 8728.8 8746.3 5099.4 5100.1 5121.2 5153.8 5256.8 2078.0 2108.7 2119.2 2123.7 2139.7 931.7 1058.2 1066.4 1077.8 1078.8 20230.2 20298.2 20377.5 20494.5 20991.8 972.9 1018.7 1028.5 1078.3 1113.8 10808.5 11479.9 11648.8 11653.0 11663.5 396379.0 398637.0 399476.0 529721.0 533120.0 80848.6 85586.0 90017.0 101006.5 101911.5 1018.6 1041.7 1059.3 1061.1 1064.9 16272.7 18977.4 19215.9 19222.0 19560.0 44877.3 45257.0 45905.3 46616.5 46648.5 65816.3 66999.0 67073.3 67146.7 67872.0 8890.4 9004.6 9036.9 9092.6 9331.0 10097.6 13041.3 13326.7 13359.1 13885.9 11598.5 11641.2 11716.1 11829.8 11940.5 11746.5 11763.4 11770.6 11948.7 12338.6 11743.8 12063.5 12169.5 12212.3 12258.8 9071.5 9130.5 9139.1 9149.7 9323.4 9117.5 11923.0 11957.9 12008.6 12059.3 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | [#3] mootools.html Cycles:180 Average:6195.47 Median:2643.33 stddev:3537.94 (133.8%) stddev-sans-first:3530.70 05:39:46 INFO - PROCESS | 32451 | Values: 1556.9 1582.0 1748.9 1811.2 1858.6 8389.3 8884.7 8901.0 9647.4 9648.7 9002.8 9338.8 9361.4 9423.8 9446.8 2523.4 2529.8 2535.4 2557.5 2558.6 2361.0 2482.8 2515.3 2519.8 2521.8 2458.7 2469.3 2491.5 2500.2 2505.7 2425.0 2436.2 2444.9 2475.4 2476.4 2435.8 2458.3 2473.6 2484.0 2485.0 9334.2 9463.2 9477.7 9498.1 9564.4 5443.9 5548.4 5562.1 5573.3 5601.9 3329.8 3400.5 3438.4 3442.4 3443.8 1175.2 1237.7 1282.9 1283.3 1303.2 12674.7 12785.4 12860.4 12870.4 12913.1 4823.3 4826.5 4861.6 4892.8 5002.1 10902.6 10920.4 10927.8 10933.7 10936.1 5429.4 5453.1 5466.8 5470.1 5472.5 4490.9 4993.4 5045.3 5075.0 5090.6 4201.4 4245.2 4291.7 4291.8 4336.6 1268.8 1270.5 1274.1 1282.9 1285.5 980.5 1009.9 1034.7 1048.9 1082.6 6310.7 6390.4 6446.0 6508.8 6567.7 463.4 491.7 494.5 500.1 500.6 4354.3 4431.4 4444.5 4453.9 4459.4 11559.0 11716.8 11840.2 11886.6 11919.3 11088.4 11801.5 11806.6 11816.1 11850.0 8730.0 8971.7 8975.9 8980.2 8999.2 6613.9 6652.0 6795.4 6811.0 6831.9 10583.1 10778.9 10874.6 10920.7 10978.1 11083.3 11093.4 11131.2 11175.8 11221.2 8241.7 8275.8 8336.4 8351.5 8408.2 9140.1 9153.0 9189.0 9195.0 9203.7 7854.9 7910.8 7912.6 7952.4 8045.0 7968.6 7980.6 8005.5 8048.7 8256.3 7399.5 7524.1 7535.0 7555.7 7577.8 5574.4 5823.4 5881.5 5909.9 5947.1 5651.6 5874.0 5898.1 6050.6 6093.4 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | [#4] prototype.html Cycles:180 Average:5080.67 Median:5706.89 stddev:1503.63 (26.3%) stddev-sans-first:1465.38 05:39:46 INFO - PROCESS | 32451 | Values: 352.7 354.7 355.3 360.1 364.4 5868.1 5900.8 5901.6 5916.0 5956.9 5540.6 5785.0 5799.4 5804.4 5862.5 4514.2 4523.7 4526.9 4533.4 4541.0 4543.7 4564.5 4585.7 4593.5 4602.4 4396.5 4557.2 4559.6 4562.3 4562.9 4499.6 4500.5 4513.4 4517.4 4551.7 3980.2 3990.2 3991.8 4010.6 4017.2 5563.3 5722.7 5744.7 5753.9 5809.3 5190.5 5193.7 5207.9 5215.2 5219.7 6094.6 6105.2 6114.5 6122.5 6150.4 4254.8 4383.7 4397.8 4402.5 4406.6 6078.2 6297.1 6310.0 6310.2 6312.3 4478.6 4488.2 4510.9 4513.5 4519.8 4554.9 4556.6 4586.6 4602.4 4606.7 5002.4 5254.4 5262.7 5269.7 5278.8 6081.1 6103.2 6113.1 6120.8 6140.2 1553.2 1570.3 1572.3 1580.2 1588.2 966.0 998.9 1015.7 1017.5 1033.5 5224.7 5241.5 5271.7 5272.9 5314.5 5565.6 5575.8 5577.5 5598.2 5611.9 4933.9 5195.3 5205.2 5212.9 5298.0 2935.6 2982.7 3028.5 3029.3 3029.4 5691.1 5768.7 5790.9 5805.5 5831.3 5933.1 6402.4 6414.8 6442.9 6451.6 6493.2 6562.5 6564.5 6568.9 6575.9 6099.8 6286.4 6289.5 6298.5 6320.8 6212.8 6221.4 6249.7 6250.6 6272.9 6242.0 6476.8 6484.4 6536.6 6558.5 4401.5 4428.7 4433.8 4464.7 4481.9 6254.8 6342.6 6369.3 6443.4 6447.8 6109.4 6128.6 6136.8 6150.6 6185.5 6125.0 6157.4 6174.3 6176.6 6188.2 5927.8 6076.8 6140.5 6150.5 6152.7 6134.2 6213.6 6223.4 6251.6 6260.9 5813.0 6040.8 6045.7 6057.6 6063.2 05:39:46 INFO - PROCESS | 32451 | 05:39:46 INFO - PROCESS | 32451 | [#5] yui.html Cycles:180 Average:20959.99 Median:5013.07 stddev:29304.84 (584.6%) stddev-sans-first:29347.90 05:39:46 INFO - PROCESS | 32451 | Values: 787.5 821.9 829.0 900.7 921.6 16448.7 17165.3 17195.7 17462.4 17820.0 15312.7 15428.8 15432.2 15436.5 15472.5 741.4 876.8 890.6 896.5 909.9 898.7 914.1 914.2 914.2 915.7 955.4 956.4 957.1 957.5 958.5 954.5 955.4 955.7 957.1 957.7 690.9 694.6 695.0 696.2 697.4 16296.4 16391.3 16419.4 16461.4 16468.6 16735.2 17177.5 17179.2 17327.1 17721.0 12921.6 13033.6 13480.9 13488.0 13546.6 4116.4 4135.2 4174.1 4180.1 4192.0 87275.6 87737.6 88098.1 91580.0 92970.5 13106.2 13284.8 13342.2 13402.5 13566.5 24731.8 24765.4 24768.8 24853.9 25950.6 9055.6 9057.4 9082.5 9087.7 9254.3 5042.8 5187.4 5237.2 5242.3 5294.9 3701.6 3852.4 3869.4 3872.6 3873.2 612.1 625.7 626.5 634.4 652.8 735.9 785.5 791.7 801.9 810.0 10508.1 10600.3 10828.7 10969.4 11152.7 728.9 756.4 761.0 783.4 787.9 4931.9 4940.4 4995.6 5030.5 5048.2 75124.6 98327.0 106599.0 127363.0 131759.0 66789.7 67016.0 68079.7 76786.0 99057.0 93995.5 94367.5 95164.0 95778.5 97167.0 9017.4 9283.0 9449.3 9463.8 9547.4 54426.0 54514.7 54878.2 55548.4 56588.4 51677.0 70499.0 73721.6 74772.0 76385.6 12612.4 12721.6 12818.2 13001.7 13086.1 14201.8 14203.4 14479.3 14505.3 14628.5 13094.9 13099.7 13241.7 13488.8 13708.7 13069.7 13344.5 13349.3 13443.5 13482.3 12732.6 12773.0 12841.7 12934.9 13022.7 10744.6 10925.9 11017.6 11044.8 11408.7 10455.8 11226.6 11232.7 11351.8 11519.9 05:39:46 INFO - PROCESS | 32451 | -------- Summary: end -------- 05:39:46 INFO - PROCESS | 32451 | 05:39:47 INFO - TEST-INFO | 32451: exit 0 05:39:47 INFO - TEST-OK | dromaeo_css | took 1111232ms 05:39:47 INFO - TEST-START | kraken 05:39:47 INFO - operating with platform_type : linux_ 05:39:47 INFO - Initialising browser for kraken test... 05:39:47 INFO - TEST-INFO | started process 32725 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc_jUng/profile http://localhost:53065/getInfo.html) 05:39:52 INFO - PROCESS | 32725 | __metrics Screen width/height:1600/1200 05:39:52 INFO - PROCESS | 32725 | colorDepth:24 05:39:52 INFO - PROCESS | 32725 | Browser inner width/height: 1024/697 05:39:52 INFO - PROCESS | 32725 | __metrics 05:39:52 ERROR - PROCESS | 32725 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:39:53 INFO - TEST-INFO | 32725: exit 0 05:39:53 INFO - Browser initialized. 05:39:53 INFO - Running cycle 1/1 for kraken test... 05:39:53 INFO - TEST-INFO | started process 380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc_jUng/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:05 INFO - PROCESS | 380 | RSS: Main: 153378816 05:40:05 INFO - PROCESS | 380 | 05:40:07 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53065/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:08 INFO - PROCESS | 380 | RSS: Main: 159027200 05:40:08 INFO - PROCESS | 380 | 05:40:11 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53065/tests/kraken/driver.html?testName=audio-dft) 05:40:11 INFO - PROCESS | 380 | RSS: Main: 157335552 05:40:11 INFO - PROCESS | 380 | 05:40:14 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53065/tests/kraken/driver.html?testName=audio-fft) 05:40:15 INFO - PROCESS | 380 | RSS: Main: 158150656 05:40:15 INFO - PROCESS | 380 | 05:40:17 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53065/tests/kraken/driver.html?testName=audio-oscillator) 05:40:18 INFO - PROCESS | 380 | RSS: Main: 157499392 05:40:18 INFO - PROCESS | 380 | 05:40:20 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53065/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:20 INFO - PROCESS | 380 | RSS: Main: 157716480 05:40:20 INFO - PROCESS | 380 | 05:40:24 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53065/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:24 INFO - PROCESS | 380 | RSS: Main: 158937088 05:40:24 INFO - PROCESS | 380 | 05:40:28 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53065/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:28 INFO - PROCESS | 380 | RSS: Main: 156270592 05:40:28 INFO - PROCESS | 380 | 05:40:31 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53065/tests/kraken/driver.html?testName=json-parse-financial) 05:40:32 INFO - PROCESS | 380 | RSS: Main: 157458432 05:40:32 INFO - PROCESS | 380 | 05:40:33 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53065/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:34 INFO - PROCESS | 380 | RSS: Main: 157671424 05:40:34 INFO - PROCESS | 380 | 05:40:36 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:36 INFO - PROCESS | 380 | RSS: Main: 157773824 05:40:36 INFO - PROCESS | 380 | 05:40:38 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:39 INFO - PROCESS | 380 | RSS: Main: 157372416 05:40:39 INFO - PROCESS | 380 | 05:40:41 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:42 INFO - PROCESS | 380 | RSS: Main: 158208000 05:40:42 INFO - PROCESS | 380 | 05:40:45 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:45 INFO - PROCESS | 380 | RSS: Main: 158838784 05:40:45 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | Cycle 1(1): loaded http://localhost:53065/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53065/tests/kraken/driver.html?testName=ai-astar) 05:40:47 INFO - PROCESS | 380 | RSS: Main: 160079872 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | __start_tp_report 05:40:47 INFO - PROCESS | 380 | _x_x_mozilla_page_load 05:40:47 INFO - PROCESS | 380 | _x_x_mozilla_page_load_details 05:40:47 INFO - PROCESS | 380 | |i|pagename|runs| 05:40:47 INFO - PROCESS | 380 | |0;ai-astar;103;110;113;107;111;111;111;111;111;122 05:40:47 INFO - PROCESS | 380 | |1;audio-beat-detection;153;115;150;179;122;150;147;117;178;117 05:40:47 INFO - PROCESS | 380 | |2;audio-dft;163;165;162;162;163;161;163;162;162;162 05:40:47 INFO - PROCESS | 380 | |3;audio-fft;76;78;75;174;77;75;76;75;162;75 05:40:47 INFO - PROCESS | 380 | |4;audio-oscillator;83;83;142;84;83;85;140;86;85;139 05:40:47 INFO - PROCESS | 380 | |5;imaging-gaussian-blur;117;117;117;117;119;117;117;118;118;118 05:40:47 INFO - PROCESS | 380 | |6;imaging-darkroom;147;150;148;147;147;147;149;150;149;147 05:40:47 INFO - PROCESS | 380 | |7;imaging-desaturate;96;96;97;95;95;97;96;96;95;95 05:40:47 INFO - PROCESS | 380 | |8;json-parse-financial;51;51;52;52;51;51;53;54;52;51 05:40:47 INFO - PROCESS | 380 | |9;json-stringify-tinderbox;61;60;60;60;61;59;61;60;60;61 05:40:47 INFO - PROCESS | 380 | |10;stanford-crypto-aes;73;73;73;73;72;72;72;74;83;72 05:40:47 INFO - PROCESS | 380 | |11;stanford-crypto-ccm;136;126;118;124;123;118;125;116;155;117 05:40:47 INFO - PROCESS | 380 | |12;stanford-crypto-pbkdf2;155;154;151;159;156;156;154;153;153;152 05:40:47 INFO - PROCESS | 380 | |13;stanford-crypto-sha256-iterative;63;63;63;63;62;61;61;62;61;63 05:40:47 INFO - PROCESS | 380 | __end_tp_report 05:40:47 INFO - PROCESS | 380 | __start_cc_report 05:40:47 INFO - PROCESS | 380 | _x_x_mozilla_cycle_collect,854 05:40:47 INFO - PROCESS | 380 | __end_cc_report 05:40:47 INFO - PROCESS | 380 | __startTimestamp1473684047728__endTimestamp 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | ------- Summary: start ------- 05:40:47 INFO - PROCESS | 380 | Number of tests: 14 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:4.78 (4.3%) stddev-sans-first:4.11 05:40:47 INFO - PROCESS | 380 | Values: 103.0 110.0 113.0 107.0 111.0 111.0 111.0 111.0 111.0 122.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:150.00 stddev:24.29 (16.2%) stddev-sans-first:25.49 05:40:47 INFO - PROCESS | 380 | Values: 153.0 115.0 150.0 179.0 122.0 150.0 147.0 117.0 178.0 117.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 05:40:47 INFO - PROCESS | 380 | Values: 163.0 165.0 162.0 162.0 163.0 161.0 163.0 162.0 162.0 162.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.50 stddev:38.96 (51.6%) stddev-sans-first:40.76 05:40:47 INFO - PROCESS | 380 | Values: 76.0 78.0 75.0 174.0 77.0 75.0 76.0 75.0 162.0 75.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:83.50 stddev:27.17 (32.5%) stddev-sans-first:28.03 05:40:47 INFO - PROCESS | 380 | Values: 83.0 83.0 142.0 84.0 83.0 85.0 140.0 86.0 85.0 139.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 05:40:47 INFO - PROCESS | 380 | Values: 117.0 117.0 117.0 117.0 119.0 117.0 117.0 118.0 118.0 118.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.50 stddev:1.29 (0.9%) stddev-sans-first:1.30 05:40:47 INFO - PROCESS | 380 | Values: 147.0 150.0 148.0 147.0 147.0 147.0 149.0 150.0 149.0 147.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 05:40:47 INFO - PROCESS | 380 | Values: 96.0 96.0 97.0 95.0 95.0 97.0 96.0 96.0 95.0 95.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.05 05:40:47 INFO - PROCESS | 380 | Values: 51.0 51.0 52.0 52.0 51.0 51.0 53.0 54.0 52.0 51.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 05:40:47 INFO - PROCESS | 380 | Values: 61.0 60.0 60.0 60.0 61.0 59.0 61.0 60.0 60.0 61.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:3.33 (4.6%) stddev-sans-first:3.53 05:40:47 INFO - PROCESS | 380 | Values: 73.0 73.0 73.0 73.0 72.0 72.0 72.0 74.0 83.0 72.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:124.50 stddev:11.85 (9.5%) stddev-sans-first:11.98 05:40:47 INFO - PROCESS | 380 | Values: 136.0 126.0 118.0 124.0 123.0 118.0 125.0 116.0 155.0 117.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:154.50 stddev:2.31 (1.5%) stddev-sans-first:2.44 05:40:47 INFO - PROCESS | 380 | Values: 155.0 154.0 151.0 159.0 156.0 156.0 154.0 153.0 153.0 152.0 05:40:47 INFO - PROCESS | 380 | 05:40:47 INFO - PROCESS | 380 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 05:40:47 INFO - PROCESS | 380 | Values: 63.0 63.0 63.0 63.0 62.0 61.0 61.0 62.0 61.0 63.0 05:40:47 INFO - PROCESS | 380 | -------- Summary: end -------- 05:40:47 INFO - PROCESS | 380 | 05:40:48 INFO - TEST-INFO | 380: exit 0 05:40:48 INFO - TEST-OK | kraken | took 61206ms 05:40:48 INFO - SUITE-END | took 1172s 05:40:48 INFO - Completed test suite (00:19:32) 05:40:48 INFO - javascript benchmark 05:40:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7686.389498229739, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3285.5564498792664, 3289.243474000981, 3310.4372256716356, 3314.8508317125725, 3328.766986310959, 5602.747876345564, 5708.11524213747, 5715.0, 5735.311762588495, 5761.1358296255585, 5754.251947246852, 5787.594868359217, 5788.884222315552, 5807.883842323162, 5827.592068555192, 5202.3497062867145, 5266.7629956652045, 5337.2794672719265, 5358.544523715489, 5360.973195134038, 5110.463401342842, 5144.1254986652175, 5146.74266286686, 5157.329547158883, 5195.350581177353, 5196.8700782480555, 5218.373795144588, 5229.267902493654, 5244.9510097980365, 5305.6551324164, 5283.022640811443, 5291.4708529147165, 5294.073537131025, 5297.390800058004, 5312.734363281859, 5030.195168773015, 5119.692818430869, 5143.27994080829, 5196.376434827807, 5253.789848406059, 5526.0, 5656.349519805234, 5681.9431805681825, 5732.140178973145, 5771.278590176228, 5966.955782738047, 6001.2498437695285, 6009.939900600982, 6010.519158467312, 6018.6689732064715, 6010.3689112643415, 6016.458518733335, 6020.819375418744, 6021.939780602182, 6115.575014303848, 5678.261825962599, 5701.059325211335, 5713.74288157034, 5751.712414379264, 5801.042827933428, 6277.24673039229, 6279.748810047547, 6282.465990390868, 6287.465565426898, 6287.528435367366, 5973.551983601247, 6007.038873780174, 6031.24609423822, 6041.637501749821, 6046.244219472566, 6137.683438940172, 6176.69116544171, 6180.598261113013, 6269.7178626961895, 6270.592411493239, 6192.380761923771, 6224.813255602339, 6266.342034086447, 6266.874662506724, 6271.216097987752, 5913.586048976616, 6215.595986260879, 6268.498520118471, 6283.465905398001, 6290.716917738621, 4612.838550650711, 4625.213713668684, 4633.0, 4665.4168228971375, 4693.55411235932, 4100.36444351126, 4141.5237247716295, 4146.730462519987, 4179.2895207814745, 4182.498100227931, 5749.683767392834, 5798.971005144947, 5866.67733274674, 5875.588708790429, 5880.235569375954, 6155.47678447337, 6190.226221722285, 6212.254529456494, 6247.094171345129, 6283.151774510474, 5966.31387390447, 5994.07091900756, 6005.669688167192, 6016.547993157968, 6022.69886505673, 5230.66618012412, 5386.730663466811, 5388.191771234347, 5389.865253368619, 5398.379186393537, 6266.749330026839, 6342.20722409708, 6350.460210882126, 6361.9045714314325, 6367.981123020295, 6124.785632502841, 6136.355682653436, 6144.907826382519, 6163.506919446523, 6175.3824617539685, 6178.721957511832, 6300.621962682254, 6317.052442133717, 6341.968290158519, 6357.237131544244, 5897.439743224387, 5977.432143946318, 5986.281646202484, 6010.338862725184, 6024.518038556817, 6062.636241825505, 6068.302145253352, 6083.6349819011, 6116.204893363834, 6116.26604807426, 5850.034744267148, 5884.941150588611, 5936.0, 5962.25471816023, 5973.0, 5898.498627616701, 5975.312839023568, 5977.701114944322, 5994.610350327215, 6002.759889604367, 5927.614705044159, 5934.584579079423, 5938.554608404474, 5938.732757025858, 5944.286685597756, 5955.9577074011, 5985.0, 6035.758569657164, 6045.063015232646, 6052.455279024909, 5920.911186332122, 5924.822255332347, 5929.970350148222, 5933.703314834327, 5974.133750606051], "value": 5657.6541877505315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.139977133956, 638.0716058135414, 662.2549631664377, 677.9728810847563, 702.8629417263636, 40697.18605627886, 46200.53799462004, 46248.53751462484, 46302.999999999956, 46539.76730116345, 45957.31064034042, 46437.0, 46530.53469465304, 46557.30164047542, 46867.0, 9084.409513381643, 9178.54107294636, 9202.539873006357, 9204.907950920471, 9214.0, 9077.95461022694, 9119.544022798867, 9183.49490778007, 9189.540522973857, 9216.216621587173, 9231.261499080058, 9274.30442716799, 9294.0, 9297.442153470813, 9298.767530811716, 9206.447613143233, 9206.49364284964, 9225.354225204239, 9244.676436324764, 9299.442033478013, 9032.0, 9052.140046695553, 9089.091090890857, 9109.635614575409, 9125.863112053325, 43497.78251108756, 43519.78240108811, 43896.78051609721, 43938.34092488615, 44269.778651106535, 3607.7294202934886, 3663.757900936999, 3673.2286219893585, 3729.589745128034, 3755.117547376364, 35082.47376289332, 35324.293514129575, 35337.293254134776, 35371.469427958604, 35486.64513354859, 4662.557057079572, 4720.126776546315, 4747.406574178228, 4755.476897541268, 4768.690035147704, 269643.0, 271330.0, 271927.0, 272211.0, 272626.6368668164, 61841.690791545756, 62215.688921555105, 62242.68878655578, 62266.68866655638, 62309.68845155745, 38614.42078368827, 39322.80338598289, 39499.605003950164, 41926.371104433456, 41946.37080443796, 17857.37499187548, 18117.0, 18181.363652272372, 18204.180811863498, 18204.45386638403, 14048.929755351362, 14091.577252682437, 14108.717825643429, 14126.293685315693, 14142.151470911778, 20005.299814506423, 20280.391588252376, 20326.390208293775, 20507.58984820295, 20568.280110196072, 7683.538987660758, 7712.807179820437, 7744.0, 7774.416918731118, 7856.882146767803, 1054.920880933933, 1077.6713102503654, 1107.0, 1149.7413082056637, 1179.8053321202078, 32119.357612847612, 32270.0, 32495.350092998007, 33239.16902077419, 33762.66237337644, 1159.5158197507205, 1160.3328086350214, 1172.8959264840194, 1195.9037317361415, 1206.5354838387284, 17075.743863842054, 17136.40022599203, 17227.82772172287, 17239.3966211182, 17267.0, 469637.0, 678186.0, 680651.0, 682181.0, 683442.0, 62866.68566657137, 63564.0, 63754.362456374845, 63940.0, 64021.35978640154, 1450.9274536273144, 1487.226642146078, 1507.660776325318, 1519.6276912156593, 1525.1459182857636, 33621.663783362834, 33692.663073369935, 34164.0, 34308.313833722685, 34414.0, 53473.73263133659, 54512.18231726448, 54628.0, 54655.72672136614, 54701.72649136729, 558865.0, 564625.0, 565399.0, 566808.0, 567823.0, 30437.695623044372, 30459.0, 30620.31588262578, 30632.387352253278, 30857.537136943414, 49223.50776492333, 49547.25679114744, 49729.50270497249, 49981.75009124931, 50076.24885626646, 42854.571454285055, 43034.0, 43660.56339436565, 43951.340729889715, 44628.0, 41517.0, 41785.16429671329, 42847.35728964006, 42941.141177175654, 43385.0, 36987.260254794215, 41157.58842411658, 41222.58777412187, 42635.14729705326, 43041.569584303754, 36006.0, 38278.264331209524, 38459.0, 38583.0, 38685.226295474495, 29208.415831683673, 29611.703882961756, 30020.69979300179, 30135.0, 30189.849050754605], "value": 21018.297727468078, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.2990915443746, 412.6492481390818, 426.5457287988292, 428.1629414494663, 441.93370994350863, 18888.90555547222, 20752.169913203474, 20814.89592552037, 21135.154593816267, 21678.132874684994, 19009.809901900975, 20002.69995950062, 20181.798182018174, 20944.581108377824, 21082.0, 5885.67628780418, 6097.817065488042, 6128.60164089335, 6134.4172303631085, 6140.84647883802, 6002.849928751794, 6026.06595977622, 6074.453299203071, 6086.482648974842, 6119.0, 5880.2355693759755, 5979.043353063511, 6044.697765111749, 6053.848653783647, 6057.878842423149, 5921.674307913085, 5984.072468767348, 5986.371430999726, 5997.6701281429505, 6030.4572588467245, 5387.488188622075, 5396.622236443451, 5400.189971504266, 5412.0, 5429.728513574306, 21790.346289611338, 22358.10567577295, 24069.51860962771, 24745.133920312703, 24912.377190570198, 2532.1011041080337, 2626.9737302627113, 2638.934026649349, 2655.9336016599545, 2679.0891097027106, 10425.478726063666, 10552.103071238955, 11017.44912754359, 11382.2032457728, 11560.653180404517, 2010.1155491583802, 2092.299079808251, 2105.1263725553877, 2114.830813534929, 2124.0547956159517, 87508.0, 87770.0, 88173.0, 89347.10652893453, 91056.0, 11546.538138474367, 11697.0, 11732.413379331, 11901.464434100486, 11991.640250792316, 16835.74746378805, 16948.0, 16948.152592370578, 16953.83046169547, 17036.659266814593, 8732.825343493094, 8783.253423459077, 8809.11908809114, 8907.0, 8931.821363572692, 8507.149285071444, 8545.572721363907, 8602.87095693565, 8728.781780455412, 8746.34402419821, 5099.4390617032095, 5100.0819852426175, 5121.206213036985, 5153.845384638467, 5256.789728410898, 2077.979220207779, 2108.694239335288, 2119.2476670781957, 2123.723915890955, 2139.7111389962156, 931.7018554062635, 1058.1640504001907, 1066.4294602387686, 1077.7736675298274, 1078.800421921947, 20230.19079236814, 20298.188072476936, 20377.49056273605, 20494.487637809176, 20991.790082098985, 972.9318947673736, 1018.6943916824923, 1028.4600584692946, 1078.3314345106085, 1113.777244551093, 10808.459577021118, 11479.885201148049, 11648.825267620992, 11653.0, 11663.533458661559, 396379.0, 398637.0, 399476.0, 529721.0, 533120.0, 80848.59575702202, 85586.0, 90017.0, 101006.49496752469, 101911.4904425473, 1018.5874720738113, 1041.7083216699184, 1059.3273271472517, 1061.0874647802884, 1064.920130990179, 16272.674546508766, 18977.43067707971, 19215.90392048087, 19222.0, 19560.021998899712, 44877.326840098074, 45257.0, 45905.31142032939, 46616.53383466122, 46648.533514665774, 65816.34183658102, 66999.0, 67073.32926670866, 67146.66426667836, 67872.0, 8890.377673562789, 9004.594793234452, 9036.909630903607, 9092.59083341238, 9331.0, 10097.646582369582, 13041.282729449971, 13326.666833328856, 13359.1316564423, 13885.930570347084, 11598.536058557564, 11641.185117041725, 11716.06271498261, 11829.76340473203, 11940.52237910474, 11746.471408786796, 11763.411829408324, 11770.646880593597, 11948.701282468008, 12338.629841104781, 11743.823842642196, 12063.457144428347, 12169.452374642986, 12212.267263964191, 12258.75482490363, 9071.501067441353, 9130.49782261982, 9139.086091391073, 9149.725508234764, 9323.393979391318, 9117.452952822852, 11923.0461563073, 11957.880421195676, 12008.579699710468, 12059.276443413422], "value": 9709.411271999823, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1556.8988015778978, 1582.0270533621822, 1748.851347635451, 1811.248331942244, 1858.637565674694, 8389.328853691706, 8884.733457996263, 8900.955495222532, 9647.42115473072, 9648.662296819613, 9002.774930626721, 9338.813223735522, 9361.391509551875, 9423.81152376952, 9446.81106377872, 2523.40699935515, 2529.7976161907004, 2535.3915060385466, 2557.4629327841176, 2558.6034164704406, 2360.9645855312183, 2482.8386154899963, 2515.295717199178, 2519.8236123471365, 2521.7856482199036, 2458.6926634170727, 2469.333280014395, 2491.5266099441137, 2500.249925022495, 2505.711843138035, 2424.9515009699708, 2436.1595249638845, 2444.8655323957264, 2475.4306509502917, 2476.418041760185, 2435.768601982809, 2458.311672731638, 2473.5547601431736, 2484.0, 2484.9751502485105, 9334.159925606727, 9463.242940564738, 9477.66828161018, 9498.050194980517, 9564.426134431887, 5443.863903402446, 5548.44515548446, 5562.110062390038, 5573.303337082864, 5601.887962240773, 3329.816860072695, 3400.523926650271, 3438.381091403546, 3442.431998720208, 3443.8450269737923, 1175.2067354535739, 1237.7276999060193, 1282.9422675979604, 1283.3390803736083, 1303.211557008006, 12674.74650506994, 12785.360731963458, 12860.356982150855, 12870.356482175854, 12913.096083274175, 4823.300621409876, 4826.469088400273, 4861.635377346643, 4892.755362231874, 5002.099622068063, 10902.563897444172, 10920.399378034126, 10927.781444371069, 10933.726656833644, 10936.125109991182, 5429.375621803464, 5453.100238460689, 5466.835994920159, 5470.070088085035, 5472.452754724575, 4490.910181796346, 4993.375828021497, 5045.344105266366, 5074.974625126924, 5090.618203634742, 4201.411802347674, 4245.150969806051, 4291.67812414064, 4291.806868690916, 4336.588024137702, 1268.7779638563215, 1270.4981532294876, 1274.0635632809876, 1282.9422675979604, 1285.5050805439973, 980.4705459052032, 1009.8636684047559, 1034.6947650443137, 1048.9423081730579, 1082.6319051522362, 6310.716017779211, 6390.4248617624635, 6446.000869865178, 6508.772192973223, 6567.67161641916, 463.367503357914, 491.7492079039715, 494.52278551198265, 500.14724894055547, 500.6345367881467, 4354.259775838115, 4431.423914891108, 4444.488883778342, 4453.866384008485, 4459.375687403766, 11559.0, 11716.824247636292, 11840.230385024946, 11886.583969561023, 11919.344436055926, 11088.445577721082, 11801.468933897995, 11806.586769463027, 11816.113791465503, 11850.0, 8730.0, 8971.685990990414, 8975.910240897769, 8980.236679882153, 8999.19007289347, 6613.900791488035, 6652.002199670088, 6795.354441328066, 6811.046453496416, 6831.863362732817, 10583.100436462986, 10778.892211078102, 10874.619388321371, 10920.672379828618, 10978.121750259801, 11083.334999900031, 11093.44532773342, 11131.220814543229, 11175.832362514731, 11221.158413118885, 8241.711540096068, 8275.83448331018, 8336.416450848383, 8351.498910065415, 8408.159184081544, 9140.085991400807, 9153.0, 9188.98921118663, 9194.95402522983, 9203.677871274473, 7854.882176767467, 7910.841783164419, 7912.564808935564, 7952.36381089523, 8044.959775201087, 7968.601569921365, 7980.561069141253, 8005.479643823133, 8048.678052877819, 8256.25693687571, 7399.48203625741, 7524.083821630791, 7534.962325188339, 7555.6977720890545, 7577.810554736176, 5574.414686458026, 5823.4467725566, 5881.470667639933, 5909.911351329648, 5947.137665038459, 5651.57613179005, 5874.0, 5898.1152827076285, 6050.6369617823075, 6093.421124993119], "value": 4871.034583088571, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.6931569534505, 354.70736642270134, 355.301831900316, 360.14465644095276, 364.4114754671205, 5868.090445980874, 5900.822975310747, 5901.616394934326, 5915.9704201478935, 5956.940430595692, 5540.584456165793, 5785.045467497868, 5799.449052340032, 5804.390538993408, 5862.530997520199, 4514.164879497295, 4523.705959112664, 4526.932096018562, 4533.365328853946, 4540.9772951135365, 4543.681942264043, 4564.5435456454425, 4585.747783871886, 4593.471750748673, 4602.401687780583, 4396.538363471821, 4557.225271703818, 4559.566841150102, 4562.315652652095, 4562.863114106565, 4499.572540608637, 4500.549945005506, 4513.368128461985, 4517.435320584927, 4551.658625603077, 3980.203959208141, 3990.221906728189, 3991.7604943703473, 4010.63904248619, 4017.1764788218347, 5563.304586926634, 5722.685252311121, 5744.655320680772, 5753.913691294634, 5809.331926828384, 5190.532852043297, 5193.68837869729, 5207.869803254911, 5215.243789650517, 5219.739013049332, 6094.634321940698, 6105.206323178003, 6114.480269177126, 6122.54080943931, 6150.354212807635, 4254.808533615995, 4383.7150585212175, 4397.824087036514, 4402.471703395581, 4406.6339434775, 6078.234403346511, 6297.086922396227, 6309.968450157766, 6310.211223597051, 6312.305646378894, 4478.596926276586, 4488.236999710057, 4510.909781804346, 4513.526079761577, 4519.819207231674, 4554.863354099382, 4556.635469162459, 4586.633069354511, 4602.401687780567, 4606.700564463299, 5002.424721157041, 5254.395744489356, 5262.71055091966, 5269.657472264291, 5278.81524146653, 6081.118237855575, 6103.2230335198265, 6113.144159817629, 6120.846978825476, 6140.17107690456, 1553.1690545558072, 1570.261976870869, 1572.2610373124612, 1580.194101008494, 1588.1820862255947, 965.9565319560637, 998.8862418403573, 1015.7359086637528, 1017.4912543728136, 1033.4625994482826, 5224.738763061831, 5241.54058321506, 5271.71005594696, 5272.947270527246, 5314.468553144655, 5565.554755619622, 5575.80120274143, 5577.525910297589, 5598.172922674105, 5611.859703507445, 4933.87665308363, 5195.2986346843445, 5205.167173252262, 5212.879230965361, 5297.973510132501, 2935.6477222732983, 2982.701729827, 3028.4700177468853, 3029.2585210384636, 3029.3941211757738, 5691.093483683013, 5768.673205162836, 5790.942090579125, 5805.548612846788, 5831.271091113611, 5933.110033495011, 6402.423781859655, 6414.775482858077, 6442.903356449657, 6451.580647257913, 6493.188351456068, 6562.50781191403, 6564.507661925375, 6568.901466478007, 6575.868482630416, 6099.786507472217, 6286.402791734778, 6289.528285378524, 6298.496120310456, 6320.810375688737, 6212.782552610637, 6221.44463884029, 6249.687515624109, 6250.624962502265, 6272.92993312215, 6241.96879015602, 6476.805695829133, 6484.416402523795, 6536.640484773383, 6558.475321974135, 4401.471823381215, 4428.734275943454, 4433.7561434121435, 4464.687471876938, 4481.865544033684, 6254.8123556293385, 6342.556021078481, 6369.33122022181, 6443.356943979255, 6447.838804029937, 6109.419605137505, 6128.63228206309, 6136.754529818936, 6150.585365366081, 6185.474234689922, 6124.969375153096, 6157.4458298753325, 6174.25908890936, 6176.598521096114, 6188.195534580475, 5927.8221653350465, 6076.78731244422, 6140.478059364826, 6150.50795936333, 6152.692365381624, 6134.2332208473945, 6213.59611625261, 6223.439890409885, 6251.562390632794, 6260.937390626035, 5813.0, 6040.7583696651645, 6045.667488288186, 6057.576469529745, 6063.151158837854], "value": 4599.388638853116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.4527203593505, 821.9136990615985, 828.9709860154895, 900.7027680865313, 921.6221349246802, 16448.671026579475, 17165.313387464517, 17195.74206386902, 17462.388816391427, 17820.0, 15312.693746125098, 15428.768568471482, 15432.151231682254, 15436.536903892873, 15472.458463953764, 741.4142827166559, 876.8246350729879, 890.5814267294394, 896.5382827843666, 909.8771665825095, 898.7258886039755, 914.1270087066869, 914.1589737441536, 914.1772404835634, 915.7298596913927, 955.3694561589352, 956.3974695941582, 957.1194501059042, 957.530809903146, 958.4584709639066, 954.4893481987139, 955.4076472587005, 955.7037318431309, 957.1146689312393, 957.6887511558701, 690.9274526174729, 694.6318451220861, 694.9687264073078, 696.195893742728, 697.3828162076549, 16296.42962496307, 16391.34434622614, 16419.42532011386, 16461.423850165305, 16468.588285292844, 16735.163241837858, 17177.484675459757, 17179.226934787963, 17327.133643317782, 17721.025343606096, 12921.643977082034, 13033.608991730263, 13480.932595337059, 13488.0, 13546.59360219195, 4116.403121547359, 4135.214309281257, 4174.060836311836, 4180.080382315886, 4191.958080419187, 87275.56362218212, 87737.56131219366, 88098.11901881028, 91580.0, 92970.53514732451, 13106.2136271824, 13284.800727989088, 13342.199468031755, 13402.53091141825, 13566.457341706418, 24731.752682473303, 24765.380865478506, 24768.752312477005, 24853.875730621592, 25950.610740838903, 9055.59249833759, 9057.411268267542, 9082.500462474494, 9087.727368178965, 9254.259659227246, 5042.84871453857, 5187.3658999051795, 5237.188235823453, 5242.318498595158, 5294.894102117936, 3701.555813302421, 3852.3836186210633, 3869.3615553433933, 3872.612738726105, 3873.1672690371142, 612.1368869893429, 625.6715224507079, 626.4518546272012, 634.352959980817, 652.7519542573887, 735.9116905971317, 785.4737325991599, 791.6793698552095, 801.8516574433748, 809.9595020248964, 10508.10854046279, 10600.25798194119, 10828.729281768019, 10969.396683182342, 11152.665420037412, 728.8578727148155, 756.3533179131873, 760.9771706848803, 783.4398405140352, 787.8857565653063, 4931.876703082452, 4940.374787052814, 4995.600013920872, 5030.547250747377, 5048.217526283432, 75124.62437687776, 98327.0, 106599.0, 127363.0, 131759.0, 66789.66605166942, 67016.0, 68079.65960170167, 76786.0, 99057.00942990478, 93995.53002234946, 94367.52816236012, 95164.0, 95778.52110739402, 97167.02832971721, 9017.413868098552, 9282.953585232031, 9449.291303152153, 9463.763405914908, 9547.379420337656, 54426.0, 54514.72742636262, 54878.176827346826, 55548.44451555433, 56588.43411565832, 51676.96646067133, 70499.0, 73721.6313918427, 74772.0, 76385.61807190928, 12612.369381530703, 12721.618351449471, 12818.230906145662, 13001.674958126123, 13086.14940028895, 14201.786973195616, 14203.431862725376, 14479.276036198358, 14505.274736263356, 14628.488002920272, 13094.869051309364, 13099.738005240033, 13241.735165296834, 13488.797668035184, 13708.65728356799, 13069.738605227652, 13344.466221351426, 13349.332533373099, 13443.529476468277, 13482.325883705971, 12732.554360597334, 12773.0, 12841.678958026125, 12934.935325323313, 13022.739545209231, 10744.623938162127, 10925.945370273415, 11017.614383496539, 11044.779104417707, 11408.657740267805, 10455.79088418212, 11226.55093796239, 11232.719182020515, 11351.829722554003, 11519.884801152217], "value": 7971.590389818415, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 113.0, 107.0, 111.0, 111.0, 111.0, 111.0, 111.0, 122.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 115.0, 150.0, 179.0, 122.0, 150.0, 147.0, 117.0, 178.0, 117.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 162.0, 162.0, 163.0, 161.0, 163.0, 162.0, 162.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 75.0, 174.0, 77.0, 75.0, 76.0, 75.0, 162.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 142.0, 84.0, 83.0, 85.0, 140.0, 86.0, 85.0, 139.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 119.0, 117.0, 117.0, 118.0, 118.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 148.0, 147.0, 147.0, 147.0, 149.0, 150.0, 149.0, 147.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 95.0, 97.0, 96.0, 96.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 52.0, 51.0, 51.0, 53.0, 54.0, 52.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 61.0, 59.0, 61.0, 60.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 72.0, 72.0, 72.0, 74.0, 83.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 126.0, 118.0, 124.0, 123.0, 118.0, 125.0, 116.0, 155.0, 117.0], "value": 125.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 151.0, 159.0, 156.0, 156.0, 154.0, 153.0, 153.0, 152.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 62.0, 61.0, 61.0, 62.0, 61.0, 63.0], "value": 62.2, "unit": "score"}]}]} 05:40:48 INFO - Return code: 0 05:40:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:49 ERROR - # TBPL SUCCESS # 05:40:49 INFO - Running post-action listener: _resource_record_post_action 05:40:49 INFO - [mozharness: 2016-09-12 12:40:49.054521Z] Finished run-tests step (success) 05:40:49 INFO - Running post-run listener: _resource_record_post_run 05:40:49 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 327430144; Read time: 0; Write time: 274504 05:40:49 INFO - TinderboxPrint: CPU usage
13.3% 05:40:49 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:40:49 INFO - TinderboxPrint: I/O write bytes / time
327,430,144 / 274,504 05:40:49 INFO - TinderboxPrint: CPU idle
8,227.7 (86.7%) 05:40:49 INFO - TinderboxPrint: CPU user
1,200.4 (12.7%) 05:40:49 INFO - TinderboxPrint: Swap in / out
0 / 0 05:40:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78364672; Read time: 0; Write time: 86144 05:40:49 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 248070144; Read time: 0; Write time: 188320 05:40:49 INFO - Running post-run listener: _upload_blobber_files 05:40:49 INFO - Blob upload gear active. 05:40:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:49 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 05:40:49 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'] 05:40:49 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 05:40:50 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:40:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:50 INFO - (blobuploader) - INFO - Done attempting. 05:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:56 INFO - (blobuploader) - INFO - Done attempting. 05:40:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:40:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:40:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:56 INFO - (blobuploader) - INFO - Done attempting. 05:40:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:57 INFO - Return code: 0 05:40:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e15292740da90f7984150998737fa15bd460360bb3b0bdcb2c0d0a2f3e3a87f176abafadc8a9e0e4a61eb74f15f4424db6014526c62cb34e7b26b1a553d3b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21c307d773f806f02ba33eff91bada1bd423b932c68e3e9b0a81771b8d7357eb100688233f3c970440b114985b4601dd3255a8668b215210fabdd54f90fb8a69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574f983b424412917e5c79b225fa3e9ac1e784b629f33f46defdc2d52674f1b77ac1119ec2b22514ea9b1c1d5513465f4b7029985d37ceb41c0c424c3f5c9487"} 05:40:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:57 INFO - Contents: 05:40:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e15292740da90f7984150998737fa15bd460360bb3b0bdcb2c0d0a2f3e3a87f176abafadc8a9e0e4a61eb74f15f4424db6014526c62cb34e7b26b1a553d3b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21c307d773f806f02ba33eff91bada1bd423b932c68e3e9b0a81771b8d7357eb100688233f3c970440b114985b4601dd3255a8668b215210fabdd54f90fb8a69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574f983b424412917e5c79b225fa3e9ac1e784b629f33f46defdc2d52674f1b77ac1119ec2b22514ea9b1c1d5513465f4b7029985d37ceb41c0c424c3f5c9487"} 05:40:57 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:57 INFO - Copying logs to upload dir... 05:40:57 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.913491 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-12 05:40:56.571080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:56.574202) ========= 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=d2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e15292740da90f7984150998737fa15bd460360bb3b0bdcb2c0d0a2f3e3a87f176abafadc8a9e0e4a61eb74f15f4424db6014526c62cb34e7b26b1a553d3b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21c307d773f806f02ba33eff91bada1bd423b932c68e3e9b0a81771b8d7357eb100688233f3c970440b114985b4601dd3255a8668b215210fabdd54f90fb8a69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574f983b424412917e5c79b225fa3e9ac1e784b629f33f46defdc2d52674f1b77ac1119ec2b22514ea9b1c1d5513465f4b7029985d37ceb41c0c424c3f5c9487"} build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011348 build_url: 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e15292740da90f7984150998737fa15bd460360bb3b0bdcb2c0d0a2f3e3a87f176abafadc8a9e0e4a61eb74f15f4424db6014526c62cb34e7b26b1a553d3b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21c307d773f806f02ba33eff91bada1bd423b932c68e3e9b0a81771b8d7357eb100688233f3c970440b114985b4601dd3255a8668b215210fabdd54f90fb8a69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574f983b424412917e5c79b225fa3e9ac1e784b629f33f46defdc2d52674f1b77ac1119ec2b22514ea9b1c1d5513465f4b7029985d37ceb41c0c424c3f5c9487"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:56.602634) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:56.603028) ========= 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=d2a8c569965ab8367867b893000001a1-1473678541.15624-1285318282 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.31 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:56.915544) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:56.915894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:56.916254) ========= ========= Total master_lag: 0.47 =========