builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1466173371.45 results: success (0) buildid: 20160617043005 builduid: 3ead635ae98c4bff919bdbca8de23207 revision: f97c058ecdeb29f13c3e1e01794cbfebd24ca815 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.448228) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.449417) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.450140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.462245) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.462896) ========= 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=4300f881736b422476174874000001bc-1466173371.528791-222818109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-17 07:22:52-- 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% 102M=0s 2016-06-17 07:22:52 (102 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118163 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.596976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.597675) ========= 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=4300f881736b422476174874000001bc-1466173371.528791-222818109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016787 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:51.651043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-17 07:22:51.651840) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f97c058ecdeb29f13c3e1e01794cbfebd24ca815 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f97c058ecdeb29f13c3e1e01794cbfebd24ca815 --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=4300f881736b422476174874000001bc-1466173371.528791-222818109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-17 07:22:52,524 truncating revision to first 12 chars 2016-06-17 07:22:52,524 Setting DEBUG logging. 2016-06-17 07:22:52,524 attempt 1/10 2016-06-17 07:22:52,524 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/f97c058ecdeb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-17 07:22:53,296 unpacking tar archive at: mozilla-inbound-f97c058ecdeb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.117961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-17 07:22:52.788602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:52.789542) ========= script_repo_revision: f97c058ecdeb29f13c3e1e01794cbfebd24ca815 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:52.790328) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:52.790686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 07:22:52.802304) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-06-17 07:22:52.802912) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=4300f881736b422476174874000001bc-1466173371.528791-222818109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:22:53 INFO - MultiFileLogger online at 20160617 07:22:53 in /builds/slave/test 07:22:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 07:22:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:22:53 INFO - {'append_to_log': False, 07:22:53 INFO - 'base_work_dir': '/builds/slave/test', 07:22:53 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 07:22:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:22:53 INFO - 'branch': 'Mozilla-Inbound', 07:22:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:22:53 INFO - 'config_files': ('talos/linux_config.py',), 07:22:53 INFO - 'default_actions': ('clobber', 07:22:53 INFO - 'read-buildbot-config', 07:22:53 INFO - 'download-and-extract', 07:22:53 INFO - 'populate-webroot', 07:22:53 INFO - 'create-virtualenv', 07:22:53 INFO - 'install', 07:22:53 INFO - 'run-tests'), 07:22:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:22:53 INFO - 'download_minidump_stackwalk': True, 07:22:53 INFO - 'download_symbols': 'ondemand', 07:22:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:22:53 INFO - 'tooltool.py': '/tools/tooltool.py', 07:22:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:22:53 INFO - '/tools/misc-python/virtualenv.py')}, 07:22:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:22:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:22:53 INFO - 'installer_path': 'installer.exe', 07:22:53 INFO - 'log_level': 'info', 07:22:53 INFO - 'log_name': 'talos', 07:22:53 INFO - 'log_to_console': True, 07:22:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:22:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:22:53 INFO - 'opt_config_files': (), 07:22:53 INFO - 'pip_index': False, 07:22:53 INFO - 'sps_profile': False, 07:22:53 INFO - 'sps_profile_interval': 0, 07:22:53 INFO - 'suite': 'dromaeojs', 07:22:53 INFO - 'system_bits': '32', 07:22:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:22:53 INFO - 'title': 'talos-linux64-ix-045', 07:22:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:22:53 INFO - 'use_talos_json': True, 07:22:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:22:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:22:53 INFO - 'work_dir': 'build'} 07:22:53 INFO - ##### 07:22:53 INFO - ##### Running clobber step. 07:22:53 INFO - ##### 07:22:53 INFO - Running pre-action listener: _resource_record_pre_action 07:22:53 INFO - Running main action method: clobber 07:22:53 INFO - rmtree: /builds/slave/test/build 07:22:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:22:54 INFO - Running post-action listener: _resource_record_post_action 07:22:54 INFO - ##### 07:22:54 INFO - ##### Finished clobber step (success) 07:22:54 INFO - ##### 07:22:54 INFO - ##### 07:22:54 INFO - ##### Running read-buildbot-config step. 07:22:54 INFO - ##### 07:22:54 INFO - Running pre-action listener: _resource_record_pre_action 07:22:54 INFO - Running main action method: read_buildbot_config 07:22:54 INFO - Using buildbot properties: 07:22:54 INFO - { 07:22:54 INFO - "project": "", 07:22:54 INFO - "product": "firefox", 07:22:54 INFO - "script_repo_revision": "production", 07:22:54 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 07:22:54 INFO - "repository": "", 07:22:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 07:22:54 INFO - "buildid": "20160617043005", 07:22:54 INFO - "pgo_build": "True", 07:22:54 INFO - "basedir": "/builds/slave/test", 07:22:54 INFO - "buildnumber": 1937, 07:22:54 INFO - "slavename": "talos-linux64-ix-045", 07:22:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:22:54 INFO - "platform": "ubuntu64_hw", 07:22:54 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 07:22:54 INFO - "branch": "mozilla-inbound", 07:22:54 INFO - "repo_path": "integration/mozilla-inbound", 07:22:54 INFO - "revision": "f97c058ecdeb29f13c3e1e01794cbfebd24ca815", 07:22:54 INFO - "stage_platform": "linux64-pgo", 07:22:54 INFO - "builduid": "3ead635ae98c4bff919bdbca8de23207", 07:22:54 INFO - "slavebuilddir": "test" 07:22:54 INFO - } 07:22:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:22:54 INFO - Running post-action listener: _resource_record_post_action 07:22:54 INFO - ##### 07:22:54 INFO - ##### Finished read-buildbot-config step (success) 07:22:54 INFO - ##### 07:22:54 INFO - ##### 07:22:54 INFO - ##### Running download-and-extract step. 07:22:54 INFO - ##### 07:22:54 INFO - Running pre-action listener: _resource_record_pre_action 07:22:54 INFO - Running main action method: download_and_extract 07:22:54 INFO - mkdir: /builds/slave/test/build/tests 07:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:22:54 INFO - https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:22:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:22:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:22:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:22:54 INFO - Downloaded 1361 bytes. 07:22:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:22:54 INFO - Using the following test package requirements: 07:22:54 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:22:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:22:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:22:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'jsshell-linux-x86_64.zip'], 07:22:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:22:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:22:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:22:54 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:22:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:22:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:22:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:22:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:22:54 INFO - https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:22:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:22:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:22:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:56 INFO - Downloaded 24220991 bytes. 07:22:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:22:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:22:57 INFO - Return code: 0 07:22:57 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:22:57 INFO - https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:22:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:22:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:22:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:58 INFO - Downloaded 24220991 bytes. 07:22:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:22:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:23:00 INFO - Return code: 0 07:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:00 INFO - https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:23:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:23:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:23:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:23:01 INFO - Downloaded 14405384 bytes. 07:23:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:23:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:23:01 INFO - Return code: 0 07:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:01 INFO - https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:23:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:23:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:23:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:23:02 INFO - Downloaded 59436058 bytes. 07:23:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:23:02 INFO - mkdir: /builds/slave/test/properties 07:23:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:23:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:23:02 INFO - Contents: 07:23:02 INFO - build_url:https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:23:03 INFO - Running post-action listener: _resource_record_post_action 07:23:03 INFO - Running post-action listener: set_extra_try_arguments 07:23:03 INFO - ##### 07:23:03 INFO - ##### Finished download-and-extract step (success) 07:23:03 INFO - ##### 07:23:03 INFO - ##### 07:23:03 INFO - ##### Running populate-webroot step. 07:23:03 INFO - ##### 07:23:03 INFO - Running pre-action listener: _resource_record_pre_action 07:23:03 INFO - Running main action method: populate_webroot 07:23:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:23:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:23:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:23:03 INFO - '--tppagecycles', 07:23:03 INFO - '7'], 07:23:03 INFO - 'tests': ['tsvgm']}}, 07:23:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:23:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:23:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:23:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:23:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:23:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:23:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['damp', 'tps']}, 07:23:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'tests': ['damp', 'tps']}, 07:23:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['dromaeo_dom']}, 07:23:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:23:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['basic_compositor_video']}, 07:23:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:23:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['a11yr', 07:23:03 INFO - 'ts_paint', 07:23:03 INFO - 'tpaint', 07:23:03 INFO - 'sessionrestore', 07:23:03 INFO - 'sessionrestore_no_auto_restore', 07:23:03 INFO - 'tabpaint']}, 07:23:03 INFO - 'other-e10s': {'tests': ['a11yr', 07:23:03 INFO - 'ts_paint', 07:23:03 INFO - 'tpaint', 07:23:03 INFO - 'sessionrestore', 07:23:03 INFO - 'sessionrestore_no_auto_restore', 07:23:03 INFO - 'tabpaint']}, 07:23:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:23:03 INFO - 'ts_paint', 07:23:03 INFO - 'tpaint', 07:23:03 INFO - 'sessionrestore', 07:23:03 INFO - 'sessionrestore_no_auto_restore', 07:23:03 INFO - 'tabpaint']}, 07:23:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:23:03 INFO - 'ts_paint', 07:23:03 INFO - 'tpaint', 07:23:03 INFO - 'sessionrestore', 07:23:03 INFO - 'sessionrestore_no_auto_restore', 07:23:03 INFO - 'tabpaint']}, 07:23:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['a11yr', 07:23:03 INFO - 'ts_paint', 07:23:03 INFO - 'tpaint', 07:23:03 INFO - 'sessionrestore', 07:23:03 INFO - 'sessionrestore_no_auto_restore', 07:23:03 INFO - 'tabpaint']}, 07:23:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['a11yr', 07:23:03 INFO - 'ts_paint', 07:23:03 INFO - 'tpaint', 07:23:03 INFO - 'sessionrestore', 07:23:03 INFO - 'sessionrestore_no_auto_restore', 07:23:03 INFO - 'tabpaint']}, 07:23:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['tsvgx', 07:23:03 INFO - 'tsvgr_opacity', 07:23:03 INFO - 'tart', 07:23:03 INFO - 'tscrollx', 07:23:03 INFO - 'cart']}, 07:23:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:23:03 INFO - 'tsvgr_opacity', 07:23:03 INFO - 'tart', 07:23:03 INFO - 'tscrollx', 07:23:03 INFO - 'cart']}, 07:23:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'talos_options': ['--disable-e10s'], 07:23:03 INFO - 'tests': ['tp5o']}, 07:23:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'tests': ['tp5o']}, 07:23:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'talos_options': ['--disable-e10s', 07:23:03 INFO - '--xperf_path', 07:23:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:23:03 INFO - 'tests': ['tp5n']}, 07:23:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:03 INFO - 'talos_options': ['--xperf_path', 07:23:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:23:03 INFO - 'tests': ['tp5n']}}, 07:23:03 INFO - 'talos.zip': {'path': '', 07:23:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:23:03 INFO - Running post-action listener: _resource_record_post_action 07:23:03 INFO - ##### 07:23:03 INFO - ##### Finished populate-webroot step (success) 07:23:03 INFO - ##### 07:23:03 INFO - ##### 07:23:03 INFO - ##### Running create-virtualenv step. 07:23:03 INFO - ##### 07:23:03 INFO - Running pre-action listener: _resource_record_pre_action 07:23:03 INFO - Running main action method: create_virtualenv 07:23:03 INFO - Creating virtualenv /builds/slave/test/build/venv 07:23:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:23:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:23:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:23:03 INFO - Using real prefix '/usr' 07:23:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:23:04 INFO - Installing distribute.............................................................................................................................................................................................done. 07:23:06 INFO - Installing pip.................done. 07:23:06 INFO - Return code: 0 07:23:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:06 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:23:06 INFO - Using env: {'DISPLAY': ':0', 07:23:06 INFO - 'HOME': '/home/cltbld', 07:23:06 INFO - 'LANG': 'en_US.UTF-8', 07:23:06 INFO - 'LANGUAGE': 'en_US:en', 07:23:06 INFO - 'LOGNAME': 'cltbld', 07:23:06 INFO - 'MAIL': '/var/mail/cltbld', 07:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:06 INFO - 'MOZ_NO_REMOTE': '1', 07:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:06 INFO - 'NO_EM_RESTART': '1', 07:23:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:06 INFO - 'PWD': '/builds/slave/test', 07:23:06 INFO - 'SHELL': '/bin/bash', 07:23:06 INFO - 'SHLVL': '1', 07:23:06 INFO - 'TERM': 'linux', 07:23:06 INFO - 'TMOUT': '86400', 07:23:06 INFO - 'USER': 'cltbld', 07:23:06 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:06 INFO - '_': '/tools/buildbot/bin/python'} 07:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:23:06 INFO - Cleaning up... 07:23:06 INFO - Return code: 0 07:23:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:06 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:23:06 INFO - Using env: {'DISPLAY': ':0', 07:23:06 INFO - 'HOME': '/home/cltbld', 07:23:06 INFO - 'LANG': 'en_US.UTF-8', 07:23:06 INFO - 'LANGUAGE': 'en_US:en', 07:23:06 INFO - 'LOGNAME': 'cltbld', 07:23:06 INFO - 'MAIL': '/var/mail/cltbld', 07:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:06 INFO - 'MOZ_NO_REMOTE': '1', 07:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:06 INFO - 'NO_EM_RESTART': '1', 07:23:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:06 INFO - 'PWD': '/builds/slave/test', 07:23:06 INFO - 'SHELL': '/bin/bash', 07:23:06 INFO - 'SHLVL': '1', 07:23:06 INFO - 'TERM': 'linux', 07:23:06 INFO - 'TMOUT': '86400', 07:23:06 INFO - 'USER': 'cltbld', 07:23:06 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:06 INFO - '_': '/tools/buildbot/bin/python'} 07:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:06 INFO - Downloading/unpacking psutil>=3.1.1 07:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:23:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:23:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:23:08 INFO - Installing collected packages: psutil 07:23:08 INFO - Running setup.py install for psutil 07:23:08 INFO - building 'psutil._psutil_linux' extension 07:23:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:23:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:23:08 INFO - building 'psutil._psutil_posix' extension 07:23:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:23:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:23:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:23:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:23:09 INFO - Successfully installed psutil 07:23:09 INFO - Cleaning up... 07:23:09 INFO - Return code: 0 07:23:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:23:09 INFO - Using env: {'DISPLAY': ':0', 07:23:09 INFO - 'HOME': '/home/cltbld', 07:23:09 INFO - 'LANG': 'en_US.UTF-8', 07:23:09 INFO - 'LANGUAGE': 'en_US:en', 07:23:09 INFO - 'LOGNAME': 'cltbld', 07:23:09 INFO - 'MAIL': '/var/mail/cltbld', 07:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:09 INFO - 'MOZ_NO_REMOTE': '1', 07:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:09 INFO - 'NO_EM_RESTART': '1', 07:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:09 INFO - 'PWD': '/builds/slave/test', 07:23:09 INFO - 'SHELL': '/bin/bash', 07:23:09 INFO - 'SHLVL': '1', 07:23:09 INFO - 'TERM': 'linux', 07:23:09 INFO - 'TMOUT': '86400', 07:23:09 INFO - 'USER': 'cltbld', 07:23:09 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:09 INFO - '_': '/tools/buildbot/bin/python'} 07:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:23:11 INFO - Installing collected packages: mozsystemmonitor 07:23:11 INFO - Running setup.py install for mozsystemmonitor 07:23:11 INFO - Successfully installed mozsystemmonitor 07:23:11 INFO - Cleaning up... 07:23:11 INFO - Return code: 0 07:23:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:11 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:23:11 INFO - Using env: {'DISPLAY': ':0', 07:23:11 INFO - 'HOME': '/home/cltbld', 07:23:11 INFO - 'LANG': 'en_US.UTF-8', 07:23:11 INFO - 'LANGUAGE': 'en_US:en', 07:23:11 INFO - 'LOGNAME': 'cltbld', 07:23:11 INFO - 'MAIL': '/var/mail/cltbld', 07:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:11 INFO - 'MOZ_NO_REMOTE': '1', 07:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:11 INFO - 'NO_EM_RESTART': '1', 07:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:11 INFO - 'PWD': '/builds/slave/test', 07:23:11 INFO - 'SHELL': '/bin/bash', 07:23:11 INFO - 'SHLVL': '1', 07:23:11 INFO - 'TERM': 'linux', 07:23:11 INFO - 'TMOUT': '86400', 07:23:11 INFO - 'USER': 'cltbld', 07:23:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:11 INFO - '_': '/tools/buildbot/bin/python'} 07:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:11 INFO - Downloading/unpacking jsonschema==2.5.1 07:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:23:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:13 INFO - Downloading functools32-3.2.3-2.tar.gz 07:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:23:13 INFO - warning: no files found matching '*.txt' 07:23:13 INFO - no previously-included directories found matching 'build' 07:23:13 INFO - no previously-included directories found matching 'dist' 07:23:13 INFO - no previously-included directories found matching '.git*' 07:23:13 INFO - Installing collected packages: jsonschema, functools32 07:23:13 INFO - Running setup.py install for functools32 07:23:13 INFO - warning: no files found matching '*.txt' 07:23:13 INFO - no previously-included directories found matching 'build' 07:23:13 INFO - no previously-included directories found matching 'dist' 07:23:13 INFO - no previously-included directories found matching '.git*' 07:23:13 INFO - Successfully installed jsonschema functools32 07:23:13 INFO - Cleaning up... 07:23:14 INFO - Return code: 0 07:23:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:23:14 INFO - Using env: {'DISPLAY': ':0', 07:23:14 INFO - 'HOME': '/home/cltbld', 07:23:14 INFO - 'LANG': 'en_US.UTF-8', 07:23:14 INFO - 'LANGUAGE': 'en_US:en', 07:23:14 INFO - 'LOGNAME': 'cltbld', 07:23:14 INFO - 'MAIL': '/var/mail/cltbld', 07:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:14 INFO - 'MOZ_NO_REMOTE': '1', 07:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:14 INFO - 'NO_EM_RESTART': '1', 07:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:14 INFO - 'PWD': '/builds/slave/test', 07:23:14 INFO - 'SHELL': '/bin/bash', 07:23:14 INFO - 'SHLVL': '1', 07:23:14 INFO - 'TERM': 'linux', 07:23:14 INFO - 'TMOUT': '86400', 07:23:14 INFO - 'USER': 'cltbld', 07:23:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:14 INFO - '_': '/tools/buildbot/bin/python'} 07:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:14 INFO - Downloading/unpacking blobuploader==1.2.4 07:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:16 INFO - Downloading blobuploader-1.2.4.tar.gz 07:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:23:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:23:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:16 INFO - Downloading docopt-0.6.1.tar.gz 07:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:23:16 INFO - Installing collected packages: blobuploader, requests, docopt 07:23:16 INFO - Running setup.py install for blobuploader 07:23:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:23:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:23:16 INFO - Running setup.py install for requests 07:23:17 INFO - Running setup.py install for docopt 07:23:17 INFO - Successfully installed blobuploader requests docopt 07:23:17 INFO - Cleaning up... 07:23:17 INFO - Return code: 0 07:23:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:17 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:23:17 INFO - Using env: {'DISPLAY': ':0', 07:23:17 INFO - 'HOME': '/home/cltbld', 07:23:17 INFO - 'LANG': 'en_US.UTF-8', 07:23:17 INFO - 'LANGUAGE': 'en_US:en', 07:23:17 INFO - 'LOGNAME': 'cltbld', 07:23:17 INFO - 'MAIL': '/var/mail/cltbld', 07:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:17 INFO - 'MOZ_NO_REMOTE': '1', 07:23:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:17 INFO - 'NO_EM_RESTART': '1', 07:23:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:17 INFO - 'PWD': '/builds/slave/test', 07:23:17 INFO - 'SHELL': '/bin/bash', 07:23:17 INFO - 'SHLVL': '1', 07:23:17 INFO - 'TERM': 'linux', 07:23:17 INFO - 'TMOUT': '86400', 07:23:17 INFO - 'USER': 'cltbld', 07:23:17 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:17 INFO - '_': '/tools/buildbot/bin/python'} 07:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:23:17 INFO - Running setup.py (path:/tmp/pip-5TWFB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:23:17 INFO - Running setup.py (path:/tmp/pip-Y6iYQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:23:17 INFO - Running setup.py (path:/tmp/pip-LNuV9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:23:17 INFO - Running setup.py (path:/tmp/pip-JivIuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:23:18 INFO - Running setup.py (path:/tmp/pip-WGzj_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:23:18 INFO - Running setup.py (path:/tmp/pip-zt7q7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:23:18 INFO - Running setup.py (path:/tmp/pip-KD9rv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:23:18 INFO - Running setup.py (path:/tmp/pip-R8KBg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:23:18 INFO - Running setup.py (path:/tmp/pip-BHZmBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:23:18 INFO - Running setup.py (path:/tmp/pip-JHCGOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:23:18 INFO - Running setup.py (path:/tmp/pip-2OE6rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:23:18 INFO - Running setup.py (path:/tmp/pip-A1xjTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:23:18 INFO - Running setup.py (path:/tmp/pip-xalDNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:23:18 INFO - Running setup.py (path:/tmp/pip-wohw0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:18 INFO - Running setup.py (path:/tmp/pip-1ERyu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:23:18 INFO - Running setup.py (path:/tmp/pip-GYJG0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:23:18 INFO - Running setup.py (path:/tmp/pip-SDAyIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:23:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:23:18 INFO - Running setup.py install for manifestparser 07:23:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for mozcrash 07:23:19 INFO - Running setup.py install for mozdebug 07:23:19 INFO - Running setup.py install for mozdevice 07:23:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for mozfile 07:23:19 INFO - Running setup.py install for mozhttpd 07:23:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for mozinfo 07:23:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for mozInstall 07:23:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for mozleak 07:23:19 INFO - Running setup.py install for mozlog 07:23:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for moznetwork 07:23:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozprocess 07:23:20 INFO - Running setup.py install for mozprofile 07:23:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozrunner 07:23:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozscreenshot 07:23:20 INFO - Running setup.py install for moztest 07:23:20 INFO - Running setup.py install for mozversion 07:23:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:23:20 INFO - Cleaning up... 07:23:20 INFO - Return code: 0 07:23:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:20 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:23:20 INFO - Using env: {'DISPLAY': ':0', 07:23:20 INFO - 'HOME': '/home/cltbld', 07:23:20 INFO - 'LANG': 'en_US.UTF-8', 07:23:20 INFO - 'LANGUAGE': 'en_US:en', 07:23:20 INFO - 'LOGNAME': 'cltbld', 07:23:20 INFO - 'MAIL': '/var/mail/cltbld', 07:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:20 INFO - 'MOZ_NO_REMOTE': '1', 07:23:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:20 INFO - 'NO_EM_RESTART': '1', 07:23:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:20 INFO - 'PWD': '/builds/slave/test', 07:23:20 INFO - 'SHELL': '/bin/bash', 07:23:20 INFO - 'SHLVL': '1', 07:23:20 INFO - 'TERM': 'linux', 07:23:20 INFO - 'TMOUT': '86400', 07:23:20 INFO - 'USER': 'cltbld', 07:23:20 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:20 INFO - '_': '/tools/buildbot/bin/python'} 07:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:23:20 INFO - Running setup.py (path:/tmp/pip-ndqp2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:23:21 INFO - Running setup.py (path:/tmp/pip-3e2wFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:23:21 INFO - Running setup.py (path:/tmp/pip-rHCCrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:23:21 INFO - Running setup.py (path:/tmp/pip-tBARB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:23:21 INFO - Running setup.py (path:/tmp/pip-vRbT1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:23:21 INFO - Running setup.py (path:/tmp/pip-Nx0isb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:23:21 INFO - Running setup.py (path:/tmp/pip-LF7BXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:23:21 INFO - Running setup.py (path:/tmp/pip-HcLLD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:23:21 INFO - Running setup.py (path:/tmp/pip-3CPVOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:23:21 INFO - Running setup.py (path:/tmp/pip-5MKK44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:23:21 INFO - Running setup.py (path:/tmp/pip-EtzQxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:23:21 INFO - Running setup.py (path:/tmp/pip-TaT3rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:23:21 INFO - Running setup.py (path:/tmp/pip-w8Gv0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:23:21 INFO - Running setup.py (path:/tmp/pip-kVzg8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:22 INFO - Running setup.py (path:/tmp/pip-Ghn144-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:23:22 INFO - Running setup.py (path:/tmp/pip-L0Ns77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:23:22 INFO - Running setup.py (path:/tmp/pip-EjZxOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:23:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:24 INFO - Downloading blessings-1.6.tar.gz 07:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:23:24 INFO - Installing collected packages: blessings 07:23:24 INFO - Running setup.py install for blessings 07:23:24 INFO - Successfully installed blessings 07:23:24 INFO - Cleaning up... 07:23:24 INFO - Return code: 0 07:23:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:23:24 INFO - Reading from file tmpfile_stdout 07:23:24 INFO - Current package versions: 07:23:24 INFO - argparse == 1.2.1 07:23:24 INFO - blessings == 1.6 07:23:24 INFO - blobuploader == 1.2.4 07:23:24 INFO - docopt == 0.6.1 07:23:24 INFO - functools32 == 3.2.3-2 07:23:24 INFO - jsonschema == 2.5.1 07:23:24 INFO - manifestparser == 1.1 07:23:24 INFO - mozInstall == 1.12 07:23:24 INFO - mozcrash == 0.17 07:23:24 INFO - mozdebug == 0.1 07:23:24 INFO - mozdevice == 0.48 07:23:24 INFO - mozfile == 1.2 07:23:24 INFO - mozhttpd == 0.7 07:23:24 INFO - mozinfo == 0.9 07:23:24 INFO - mozleak == 0.1 07:23:24 INFO - mozlog == 3.2 07:23:24 INFO - moznetwork == 0.27 07:23:24 INFO - mozprocess == 0.22 07:23:24 INFO - mozprofile == 0.28 07:23:24 INFO - mozrunner == 6.11 07:23:24 INFO - mozscreenshot == 0.1 07:23:24 INFO - mozsystemmonitor == 0.3 07:23:24 INFO - moztest == 0.7 07:23:24 INFO - mozversion == 1.4 07:23:24 INFO - psutil == 3.1.1 07:23:24 INFO - requests == 1.2.3 07:23:24 INFO - wsgiref == 0.1.2 07:23:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:24 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:23:24 INFO - Using env: {'DISPLAY': ':0', 07:23:24 INFO - 'HOME': '/home/cltbld', 07:23:24 INFO - 'LANG': 'en_US.UTF-8', 07:23:24 INFO - 'LANGUAGE': 'en_US:en', 07:23:24 INFO - 'LOGNAME': 'cltbld', 07:23:24 INFO - 'MAIL': '/var/mail/cltbld', 07:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:24 INFO - 'MOZ_NO_REMOTE': '1', 07:23:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:24 INFO - 'NO_EM_RESTART': '1', 07:23:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:24 INFO - 'PWD': '/builds/slave/test', 07:23:24 INFO - 'SHELL': '/bin/bash', 07:23:24 INFO - 'SHLVL': '1', 07:23:24 INFO - 'TERM': 'linux', 07:23:24 INFO - 'TMOUT': '86400', 07:23:24 INFO - 'USER': 'cltbld', 07:23:24 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:24 INFO - '_': '/tools/buildbot/bin/python'} 07:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:23:24 INFO - Cleaning up... 07:23:24 INFO - Return code: 0 07:23:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:24 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 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:23:24 INFO - Using env: {'DISPLAY': ':0', 07:23:24 INFO - 'HOME': '/home/cltbld', 07:23:24 INFO - 'LANG': 'en_US.UTF-8', 07:23:24 INFO - 'LANGUAGE': 'en_US:en', 07:23:24 INFO - 'LOGNAME': 'cltbld', 07:23:24 INFO - 'MAIL': '/var/mail/cltbld', 07:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:24 INFO - 'MOZ_NO_REMOTE': '1', 07:23:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:24 INFO - 'NO_EM_RESTART': '1', 07:23:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:24 INFO - 'PWD': '/builds/slave/test', 07:23:24 INFO - 'SHELL': '/bin/bash', 07:23:24 INFO - 'SHLVL': '1', 07:23:24 INFO - 'TERM': 'linux', 07:23:24 INFO - 'TMOUT': '86400', 07:23:24 INFO - 'USER': 'cltbld', 07:23:24 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:24 INFO - '_': '/tools/buildbot/bin/python'} 07:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:23:25 INFO - Cleaning up... 07:23:25 INFO - Return code: 0 07:23:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:25 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f11ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x298e350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, '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': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:23:25 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', 'flake8'] in /builds/slave/test/build 07:23:25 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 flake8 07:23:25 INFO - Using env: {'DISPLAY': ':0', 07:23:25 INFO - 'HOME': '/home/cltbld', 07:23:25 INFO - 'LANG': 'en_US.UTF-8', 07:23:25 INFO - 'LANGUAGE': 'en_US:en', 07:23:25 INFO - 'LOGNAME': 'cltbld', 07:23:25 INFO - 'MAIL': '/var/mail/cltbld', 07:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:25 INFO - 'MOZ_NO_REMOTE': '1', 07:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:25 INFO - 'NO_EM_RESTART': '1', 07:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:25 INFO - 'PWD': '/builds/slave/test', 07:23:25 INFO - 'SHELL': '/bin/bash', 07:23:25 INFO - 'SHLVL': '1', 07:23:25 INFO - 'TERM': 'linux', 07:23:25 INFO - 'TMOUT': '86400', 07:23:25 INFO - 'USER': 'cltbld', 07:23:25 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:25 INFO - '_': '/tools/buildbot/bin/python'} 07:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:25 INFO - Downloading/unpacking flake8 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:23:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:23:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:23:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:23:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:23:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:23:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:23:27 INFO - no previously-included directories found matching 'docs/_build' 07:23:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:23:27 INFO - Running setup.py install for pep8 07:23:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:23:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:23:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:23:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:23:27 INFO - no previously-included directories found matching 'docs/_build' 07:23:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:23:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:23:28 INFO - Cleaning up... 07:23:28 INFO - Return code: 0 07:23:28 INFO - Running post-action listener: _resource_record_post_action 07:23:28 INFO - Running post-action listener: _start_resource_monitoring 07:23:28 INFO - Starting resource monitoring. 07:23:28 INFO - ##### 07:23:28 INFO - ##### Finished create-virtualenv step (success) 07:23:28 INFO - ##### 07:23:28 INFO - ##### 07:23:28 INFO - ##### Running install step. 07:23:28 INFO - ##### 07:23:28 INFO - Running pre-action listener: _resource_record_pre_action 07:23:28 INFO - Running main action method: install 07:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:23:28 INFO - Reading from file tmpfile_stdout 07:23:28 INFO - Detecting whether we're running mozinstall >=1.0... 07:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:23:28 INFO - Reading from file tmpfile_stdout 07:23:28 INFO - Output received: 07:23:28 INFO - Usage: mozinstall [options] installer 07:23:28 INFO - Options: 07:23:28 INFO - -h, --help show this help message and exit 07:23:28 INFO - -d DEST, --destination=DEST 07:23:28 INFO - Directory to install application into. [default: 07:23:28 INFO - "/builds/slave/test"] 07:23:28 INFO - --app=APP Application being installed. [default: firefox] 07:23:28 INFO - mkdir: /builds/slave/test/build/application 07:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:23:45 INFO - Reading from file tmpfile_stdout 07:23:45 INFO - Output received: 07:23:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:23:45 INFO - Running post-action listener: _resource_record_post_action 07:23:45 INFO - ##### 07:23:45 INFO - ##### Finished install step (success) 07:23:45 INFO - ##### 07:23:45 INFO - ##### 07:23:45 INFO - ##### Running run-tests step. 07:23:45 INFO - ##### 07:23:45 INFO - Running pre-action listener: _resource_record_pre_action 07:23:45 INFO - Running main action method: run_tests 07:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:23:45 INFO - Python 2.7.3 07:23:45 INFO - Return code: 0 07:23:45 INFO - grabbing minidump binary from tooltool 07:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1d1c0>, '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 0x2a1e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a1e550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:23:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:23:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:23:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:23:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:23:45 INFO - Return code: 0 07:23:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:23:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:23:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:23:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:23:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:23:46 INFO - Return code: 0 07:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:23:46 INFO - Using env: {'DISPLAY': ':0', 07:23:46 INFO - 'HOME': '/home/cltbld', 07:23:46 INFO - 'LANG': 'en_US.UTF-8', 07:23:46 INFO - 'LANGUAGE': 'en_US:en', 07:23:46 INFO - 'LOGNAME': 'cltbld', 07:23:46 INFO - 'MAIL': '/var/mail/cltbld', 07:23:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:23:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:46 INFO - 'MOZ_NO_REMOTE': '1', 07:23:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:23:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:46 INFO - 'NO_EM_RESTART': '1', 07:23:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:46 INFO - 'PWD': '/builds/slave/test', 07:23:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:23:46 INFO - 'SHELL': '/bin/bash', 07:23:46 INFO - 'SHLVL': '1', 07:23:46 INFO - 'TERM': 'linux', 07:23:46 INFO - 'TMOUT': '86400', 07:23:46 INFO - 'USER': 'cltbld', 07:23:46 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1466173371.528791-222818109', 07:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:46 INFO - '_': '/tools/buildbot/bin/python'} 07:23:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:23:46 INFO - mozversion application_buildid: 20160617043005 07:23:46 INFO - mozversion application_changeset: f97c058ecdeb29f13c3e1e01794cbfebd24ca815 07:23:46 INFO - mozversion application_display_name: Nightly 07:23:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:23:46 INFO - mozversion application_name: Firefox 07:23:46 INFO - mozversion application_remotingname: firefox 07:23:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:23:46 INFO - mozversion application_vendor: Mozilla 07:23:46 INFO - mozversion application_version: 50.0a1 07:23:46 INFO - mozversion platform_buildid: 20160617043005 07:23:46 INFO - mozversion platform_changeset: f97c058ecdeb29f13c3e1e01794cbfebd24ca815 07:23:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:23:46 INFO - mozversion platform_version: 50.0a1 07:23:46 INFO - using testdate: 1466173426 07:23:46 INFO - actual date: 1466173426 07:23:46 INFO - starting webserver on 'localhost:50089' 07:23:46 INFO - SUITE-START | Running 2 tests 07:23:46 INFO - TEST-START | dromaeo_css 07:23:46 INFO - operating with platform_type : linux_ 07:23:46 INFO - Initialising browser for dromaeo_css test... 07:23:46 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcbTOcz/profile http://localhost:50089/getInfo.html) 07:23:50 INFO - PROCESS | 2300 | __metrics Screen width/height:1600/1200 07:23:50 INFO - PROCESS | 2300 | colorDepth:24 07:23:50 INFO - PROCESS | 2300 | Browser inner width/height: 1024/697 07:23:50 INFO - PROCESS | 2300 | __metrics 07:23:51 INFO - PROCESS | 2300 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:23:52 INFO - TEST-INFO | 2300: exit 0 07:23:52 INFO - Browser initialized. 07:23:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:23:52 INFO - TEST-INFO | started process 2458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcbTOcz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:24:04 INFO - PROCESS | 2458 | RSS: Main: 173993984 07:24:04 INFO - PROCESS | 2458 | 07:24:55 INFO - PROCESS | 2458 | 1466173495732 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:27:04 INFO - PROCESS | 2458 | Cycle 1(1): loaded http://localhost:50089/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50089/tests/dromaeo/cssquery-ext.html) 07:27:05 INFO - PROCESS | 2458 | RSS: Main: 249446400 07:27:05 INFO - PROCESS | 2458 | 07:30:08 INFO - PROCESS | 2458 | Cycle 1(1): loaded http://localhost:50089/tests/dromaeo/cssquery-ext.html (next: http://localhost:50089/tests/dromaeo/cssquery-jquery.html) 07:30:08 INFO - PROCESS | 2458 | RSS: Main: 262705152 07:30:08 INFO - PROCESS | 2458 | 07:33:10 INFO - PROCESS | 2458 | Cycle 1(1): loaded http://localhost:50089/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50089/tests/dromaeo/cssquery-mootools.html) 07:33:11 INFO - PROCESS | 2458 | RSS: Main: 263467008 07:33:11 INFO - PROCESS | 2458 | 07:36:13 INFO - PROCESS | 2458 | Cycle 1(1): loaded http://localhost:50089/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50089/tests/dromaeo/cssquery-prototype.html) 07:36:14 INFO - PROCESS | 2458 | RSS: Main: 252203008 07:36:14 INFO - PROCESS | 2458 | 07:39:16 INFO - PROCESS | 2458 | Cycle 1(1): loaded http://localhost:50089/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50089/tests/dromaeo/cssquery-yui.html) 07:39:17 INFO - PROCESS | 2458 | RSS: Main: 221011968 07:39:17 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | Cycle 1(1): loaded http://localhost:50089/tests/dromaeo/cssquery-yui.html (next: http://localhost:50089/tests/dromaeo/cssquery-dojo.html) 07:42:20 INFO - PROCESS | 2458 | RSS: Main: 284942336 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | __start_tp_report 07:42:20 INFO - PROCESS | 2458 | _x_x_mozilla_page_load 07:42:20 INFO - PROCESS | 2458 | _x_x_mozilla_page_load_details 07:42:20 INFO - PROCESS | 2458 | |i|pagename|runs| 07:42:20 INFO - PROCESS | 2458 | |0;dojo.html;3114.1591770222035;3129.593152890124;3137.4038932602803;3188.521721741737;3191.250056236784;5275.947240527603;5311.9468805311935;5338.919916201256;5467.535259502942;5497.367802702685;5286.894262114755;5327.786888524454;5346.064438723227;5375.650582712121;5411.107167317388;4806.471288158308;4856.708597484145;4898.608111351101;4899.926501102486;5071.56891664209;4971.204607262839;4994.300797888299;5024.271480635306;5055.090083784918;5060.898782024357;4792.7124372537755;4816.181249187629;4833.879153021167;4877.0489754497885;4928.531789479994;4865.367502224688;4901.289313049623;4904.779284932187;4952.5542701157065;5004.574611158056;4905;4928.507149285078;4937.827176048857;4950.232713929347;4985.451600323998;5191.558717509016;5287.312649355597;5385.8114965976;5406.972965135188;5432.266644003049;5765.106408506688;5804.912926306109;5821.67980761058;5848.649081055108;5857.033589457735;5599.636023658449;5861.384554621789;5864.76540938362;5878.706064696748;5883.264591926009;5213.165893457029;5216.347956505437;5266.868328291824;5337.306150200527;5340.25236466895;5812.476877081083;5818.679972601463;5821.126830975388;5825.0679891217205;5839.678817664984;5475.835724928259;5602.411746766612;5712.971435142881;5734.6845923473775;5741.282339707536;5704.657720536698;5811.4479124483105;5850.736716847667;5857.238558987305;5858.589898707135;5763.250777398911;5776.5089967352405;5780.53755699543;5781.392953739881;5783.2192653992015;5503.642263252876;5728.226689396878;5796.333421656479;5798.9130163047585;5799.420057994167;4358.934615980763;4397.07661391105;4456.509783923766;4470.530594287618;4480.753558554311;3981.243563722883;3984.242993831163;3989.960100399017;4018.176273863851;4023.5171779386073;5248.396434410091;5505.642133261325;5507.476789704971;5521.641093328921;5523.696196709139;5696.3733989261145;5706.115552089433;5774.48029677331;5782.508486778671;5804.564657650693;5465.9180112298345;5469.835904922858;5470;5471.316085489314;5475.534579560703;4904.60763138948;4925;5033.295338652591;5042.193249080129;5087.211482220262;5856.502197313191;5918.496927761103;5933.169356290123;5940;5989.371116032841;5731.742071606871;5745.339285982165;5750.079987202028;5751.654900705971;5753.71231438418;5821.185034095145;5833.445822646841;5898.705064746659;5903.439173278532;5907.379725128972;5715.142728590745;5717.42825717425;5725.112607545837;5725.914111288417;5766.423357664368;5734.971325143348;5759.337676167127;5792.623479473821;5811.854703632443;5819.912701309399;5416.079266524622;5534.695591742493;5643.71781410936;5646.7458964347525;5648.915266270927;5627.493525582553;5633.295838020247;5635.943640563706;5642.746076426489;5648.039833228443;5491.203775452457;5537.169424586184;5538.363088244903;5539.778408863762;5542.9168562472405;5451.536619387396;5511.338639363302;5553.222548843084;5578.386377498391;5660.575456840836;5459.2902922619805;5484.698341591251;5547.44525547442;5566.304211973504;5568.303962004749 07:42:20 INFO - PROCESS | 2458 | |1;ext.html;579.3482332376077;608.8934436473613;611.6452457574608;618.9442950134487;631.4506379449878;30182;30728.84635576822;32923.341533169325;32932.83533582335;33018.66981330186;32838.179045523815;43343.34984975228;44023.559764402344;44212.336814947725;48228.51771482284;8798.824023519543;8905.688300909469;8913.509756963364;8917.77705557363;8919.821603567925;8745.08176641453;8797.912020879805;8837.867431988494;8846.292296616255;8847.115288471166;8930.687425940094;8960.775980600505;8966.506842123681;8968.372213945026;8969.641214351419;8844.894388201476;8918.821623567525;8930.419522731001;8957.507337096456;8962.59668314924;8795.78010549735;8841.469511829246;8863.069377715312;8863.379563430562;8870.86693699595;42438.78780606108;42695.57304426978;42890.356644650354;43022.35466468005;43142.568574314486;3491.17957280039;3645.343838109139;3919.0790164311366;4039.575844536311;4392.758398288093;31913.361732765214;32543.837280813445;32894.835525822455;32918.835405822814;33095;4347.217500849846;4353.564643535653;4424.765567929399;4473.865784026484;4590.632749380042;226375;228493;229667;229829;230309;57802;58038.4196158033;58194.7090264546;58244.417555824744;58273.70863145742;60683.39316606778;60696;60698;60759.69620151871;60815.39184608186;16969.321227150776;17265.223064962105;17284;17289.740653890203;17291.82708172902;13385.933070334586;13517.188968661912;13568.525101621395;13570.117942333716;13578.524751633646;15543.222838858239;16285.185740713154;16355.427560035341;16365.918170409072;16511.17444127813;5825.38833422493;5882.911756323659;5986.431289027535;6101.206843110367;6129.816105516842;998.9860291803825;1009.3388830316155;1073.951672174754;1094.0973696700096;1120.618989543559;31144.377112457623;31564.684353156634;31870.521942170886;31959.680403196136;32478.837605812292;1121.4224674292745;1127.5489804078434;1179.979317890036;1208.9214201076902;1274.7323062156865;16566.17169141538;16687.415940442028;16700.916495417445;16814.710380662662;17082.316707331815;515752;516981;517544;520606;523915;57216.71391643015;57299;57370.13944790832;57373;57460;1260.7415479826614;1336.7927971164486;1361.9795703064462;1387.0914550969333;1393.4356585582855;31537.21156971094;32604.51093233556;32710;33082.64665170902;33225;49337.50662493473;49838.25242621436;50181.49818501914;50191.24713129233;50192.24711629401;383661;383810;385270;385793;385826;33877.62856600113;34228.315433691685;35253;41381.58618413777;41849.79075104605;38271.23457530778;45934.88844925228;47190.76404617955;48086.759566201945;48630.513694862595;39502.802485987384;40549.59450405576;40799;41827;42662.78668606637;39744.801275993435;40256.396154057125;40461.79769101136;40867.59132408757;41208.58791412167;40286.79856600698;40697.59302406938;40732.59267407288;41425.79287103545;41559;34243.4863477053;35508.289834203686;36506;36577.268454630226;36606.81696591606;26856.462870742867;27349;27718.72281277242;27819.30451738642;27867.860660696566 07:42:20 INFO - PROCESS | 2458 | |2;jquery.html;357.2996926024989;371.7825072332685;376.7875155758619;386.79499865071506;405.08652987513153;17785.110744462774;19087.904560477196;19813.70279445806;20287.391378258657;20294.594108117864;17935.82064179358;18517;18595.442136735885;18657.90671046643;18861.15124819383;5575.191597218401;5601.467860553241;5753;5799.855003624901;5854.560907931901;3895.2988462076814;5551.417101204356;5804.738786754606;5828;5837.737301821407;5616.775328986836;5862.794802181925;5875.471207591316;5875.588708790387;5885.793997210077;5695.288088988877;5718.485336319731;5741.397153298885;5749.741261643236;5751.913721294184;5524.640898341595;5578.44215578443;5587;5591.161325801122;5828.2131912191735;12612.117151799377;12618.81071783924;12809.807852882213;12843.100982931199;12939.48242070316;2173.793489618484;2358.740538540759;2460.667809845666;2477.2692055843568;2754.132448278786;9773.706788796277;9834.409935403826;10132.696019119438;10532.315399499008;10559.313644613076;1881.3039175505014;1925.4031250312448;1991.2831380702935;1993.451800754804;2020.9592060089067;79803;80889;84614.15385846124;85090.99999999937;85157.57421212917;10882;10996.560137594566;11007.834882476769;11080.667579972614;11176.05003574689;14922.104673719394;15013.774793378108;15069.698606027818;15087;15105.47130850415;8072.515649061075;8234.423590348619;8251.092379838214;8376.664933402717;8390.622421991025;8145.755627331189;8174.7956301092945;8180.263776260165;8333.416660833806;8341.791455213668;4679.67242293043;4773.737444440589;4783.23468245086;4789.425268967746;4876.780544875489;1746.7117925542398;1755.8249325233037;1917.511034686174;1947.2113793913488;2013.677811550145;911.2755359489222;1012.0587853296505;1024.5850430575629;1055.7835643693024;1065.169168048923;18791.718124228148;19143.521411964535;20455.8977205116;20643.17427302891;20671.17315307371;1045.0437849367743;1051.558345494894;1052.4950617530228;1056.99471502642;1073.6778966310076;10384;10905.18211134168;10935.343879367105;10979.725506862393;11027;486943;494624;494960;496552;500226;95517;99055;99556.50221748989;99746;99748.50125749325;977.8630991661173;991.2912267728465;991.7223177510165;994.4232345239743;1033.7570670892485;16025.358985640443;17184;17418.738718918972;17440.563985900455;17968.91015544914;43163;43427;43700;44156.116877661625;44955.32567011563;57405.42594574001;57918.42081579299;58769.412305876394;58858.41141588529;59686.70156649189;7865.213478652089;7976.880346794686;8062.113167551682;8074.636641351271;9022.413543119676;8970.955145224232;12950.80573791375;13297.933510332774;13592.388342524808;13660.795088073886;11887.643370698894;12171.695707607381;12318.384080795746;12473.126881118234;12549.121561490609;11878.287302761863;12359.25844449336;12363.62909112728;12370.319632420304;12673.366331683195;12444.813327800272;12486.188397754117;12611.93694031524;12841.550545730855;12866.67833304175;7656;7984.800379990546;8935.687250946447;9048.321375896965;9177.11869066853;10874.510647021061;11154.219204655596;11638.418079095842;11779.116566257737;12143.939280303543 07:42:20 INFO - PROCESS | 2458 | |3;mootools.html;1311.5278499740086;1442.7186698593775;1479.6596782739969;1606.4377467886234;1775.9911200443996;8215.548144852031;8650.956745216274;8655.43739656922;9158.58786354614;9167.90832091679;8842.292616590674;9077.72766816995;9081;9087.500187489686;9094.636214551427;2054.352878843167;2111.788821117884;2113.9788602113936;2325.8604483730946;2353.6587194856734;2302.0791683326634;2383.475635360218;2384.391980045095;2398.7121545414575;2402.147237730598;2180.051677520276;2215.4239897626653;2221.555688862226;2241.3051953894346;2249.9662505062356;2011.7787043425212;2231.3752149398197;2265.6148454762647;2270.3642979965553;2272.3069463813613;2326.302109367183;2347.882605869717;2371.845830021043;2372.4424760181346;2373.5252949410187;8663.653453861838;8715.346349023848;8725.912740872573;8728.301735861116;8733.25767309773;5114.616403769732;5236.057509648262;5237.711925844077;5241.79032838686;5244.60665450089;3183.3951549205576;3331.833408329574;3396.456566949301;3402.3705614461155;3406.9488957665653;1091.5088210305394;1105.6461932181705;1110.5169251375678;1124.1905827803969;1159.0495793449375;11973.46119424628;12203.755924881452;12256.387180640932;12925.418356173901;12973.286469244187;4637.744924029211;4652.674312798072;4772.331873537708;4785.449673287547;4793.688410253322;10111.292209545261;10120.291579589506;10122;10128.746781330377;10137.341072830242;5339.172428273546;5371.57027437804;5373.35519737634;5375.784968601212;5384.78460861569;4362.76004819738;4745.050989802054;4893.486183950705;4929.211326187793;4982.9252561211615;3874.3219936511;3894.1432884765304;3967.5238971323624;3988.780617066031;3999.0402303446963;1110.3337997201616;1114.2979922648678;1126.2116518437126;1141.1897552737482;1162.5526219856333;909.9454032758056;915.4370062411568;919.6919032124314;928.4614923344444;941.8257622339889;5608.887822243532;5701.177916931063;5752.741126649311;5886.234789477427;6016.217891674142;882.4043770455046;888.3159966825508;965.6185806606351;986.2603047714215;990.8266053440764;3743.5320584926885;3891.2412079644764;3900.551436584829;3937.1928754605237;4029.3955906613655;11104.888951110546;11363.318200907972;11460.885391146148;11561.30632162073;11612.12909031826;11421.086313094933;11430.028447581883;11449.313041217554;11459.656210313704;11478.540858365572;7998.040235171817;8390.496570205807;8542.18849209324;8542.44474109181;8547.10255423184;5859.472647461749;5898.852528686817;5909.202257695156;6067.726952287069;6090.69546522681;9782.559784809842;10195.184385249311;10520.842187367349;10540;10555;10316.27786054969;10320.690379288633;10325.432101234504;10338.172946164439;10722.356658600509;8084.63619137152;8290.709825156331;8353.624086915981;8357.289630381485;8368.916310836814;8804.295656347605;8806.867896981423;8843.55782210874;8866;8875.023747387911;8095.87856182146;8104.675812967415;8116.391270654843;8119.512829230265;8295.95852020736;7895.7236496722335;7901.328387086932;7925.722599708983;7935.603219838869;8121.71573994931;7085.50401471892;7109.644517774194;7136.107986501687;7149.499535032498;7162.140543134858;4757.952420475889;4957.182064959241;5054.014467178865;5090.363704536933;5228.268042473981;5143.768530416065;5164.070467315919;5186.688798672092;5253.343332083489;5276.472352764693 07:42:20 INFO - PROCESS | 2458 | |4;prototype.html;299.84557952654376;314.4497130022461;318.50631521142253;325.1237913822249;327.6330509828992;5178.844634660957;5219.765110570024;5342.370576974026;5386.434424385442;5395.973020134894;5267.157254839227;5324.680519168853;5327.813526526573;5347.598930080249;5348.117560602505;4181.351890456984;4184.4141820145205;4188.345603486621;4190.9580904191025;4202.558731333212;3908.8045597720143;3973.920521589581;4154.797957082751;4193.51774545928;4195.559466256045;4049.5343035550845;4080;4082.816273267695;4128.442660240874;4134.64855487284;4102.79486025699;4133.21468920904;4149.771762553059;4170.374443833449;4171.353440216772;3431.759776815624;3679.632036796299;3682.2451397463724;3683.521142251517;3687.6128006559193;5165.199394093921;5186.1442861927735;5197.324347834795;5201.817936372247;5227.790888364499;4663.062270769394;4673.798448063088;4680.157571637104;4691.202495575761;4692;5453.23654688344;5544.362398324204;5559.722013899329;5583.748731307101;5599.496045355938;3893.357595996657;3930.272899513599;3938.6061393860673;3945.402112144586;3966.4050392441363;5795.739191736382;5819.447152520546;5825.116567299172;5831.475167234927;5847.3275573308765;3929.724919255682;4053.452783874139;4077.653399461079;4088.550259471456;4095.4061661059577;4116.958830411718;4124.381342798604;4130.648894843972;4135.29699951003;4145.958540414617;4713.151632706145;4729.69256998294;4734.86363272812;4748;4751.3348131261655;5526.143447765603;5617.466340697627;5634.098544232903;5689.862027594415;5694.8928896308635;1410.4358256697199;1412.8485284493106;1421.8293804743496;1425.6222101143205;1432.1407155706702;874.7419511244071;882.7307671160229;887.2281115429489;891.8985053458973;912.7535565397305;4763.45220299663;4802.871325238566;4814.20565606678;4814.990630855716;4851.805927762921;4993.076280888047;5009.5491405773655;5019.322391477177;5044.445111037783;5077.720725360066;4768.427788665313;4804.35141255926;4809.44691360498;4812.422509298906;4970.2793095001025;3054.701751755495;3056.2359410147465;3061.173483159579;3070.8925187618775;3071.7405863596264;5879.588898664289;5919.67441790697;5921.911171332434;5923.815236952627;5928.288605367297;5593.629560757559;5874.471297583238;5882.970585147047;5896.734646940898;5906.2912450505355;5924.19312109802;5935.347111817697;5944.138099975479;5962.40375962409;5964.985952388104;5554.805581804779;5724.971375143097;5764.83818568037;5767.567432442667;5774.845030993734;5687.516561092353;5711.657300561979;5713.257276554022;5718.428157184415;5725.997950358754;5927.9844852750275;5933.249691340979;5938;5969.432903874299;5980.252468441445;3733.5519737631657;3801.4107813288983;4058.5535591084367;4064.0695669556726;4069.7761623111014;5639.436056394327;5669.489745922721;5685.8862822742485;5688.601797874275;5708.743106560298;5502;5511.972440137773;5542.448114527939;5551.833444996657;5559.944400555943;5309.628326017296;5417.485338892799;5568.081266590967;5646.011947909017;5653.152027195953;5453.7545810439415;5464.3442786865835;5471.480209380103;5498.3127109111365;5506.944930550643;5366.558216011784;5662.2639056922335;5676.233708449472;5685.744141513559;5702.287214098238;5481.260029895913;5507.586930980113;5512.889742205213;5534.861628459321;5558 07:42:20 INFO - PROCESS | 2458 | |5;yui.html;736.8268456912624;767.6890859202024;769.9730509432171;792.5046845721424;793.2226418110256;15349.539513814589;15909.44316948907;16480;16545.751813722803;16570.171491425426;13956.93021534891;14002.509912153077;14043.578692639237;14075.859241407583;14092.154470731737;656.2387630348796;774.8489044636298;795.9840803183932;807.9394045446616;808.2644793238154;790.9406794490408;798.9720359787409;799.0092285565886;799.3645052183529;800.7957970717459;841.3017195727562;847.2925107535185;848.2154007543029;848.3298194426395;848.3340577646549;687.9071325371061;810.0320117459638;837.7696133563319;842.7134774176778;843.9198276163754;615.806021103351;616.5221952986426;616.7070641445277;616.8334549671608;617.009699432409;15721;15727.44953926607;15743.134127622976;15750.921245393814;15807.972481788647;14393.856061439355;14832.555023349316;14859.108453492718;14877.18175500347;15028.849711502964;12837.614871553867;12999.805002924964;13143.27711975841;13163.210207387492;13353.732925341536;4494.7078439901625;4624.445066591997;4708.858734237979;4727.739974301412;4730.976345118287;73310.63344683296;73834.63082684606;74157.62921185413;74572.62713686451;74674.62662686706;11689.473973671034;11694.532218711156;11721.494570513276;11815.704607384712;11942.582009629621;22113.226037088625;22293.55412891733;22299.888500557394;22318;22684.092636294365;8780.429272097294;8793.472391656522;8846.778830529289;8953.920503316942;9342.2526197904;4880.194767863263;4899.926501102415;4942.8764280893265;4949.7030178189425;5089.720065396365;3491.965080349215;3605.134767655744;3606.6573675500786;3608.350496910529;3611.2777444511203;605.0923614578132;615.0466776496413;619.4703528483082;626.5770604841714;633.8573820890355;572.4962033410573;620.2525956222831;669.4443611802193;702.6346299924113;713.653877869233;9199.954000229956;9460.148586627236;9484.288678349152;9515.33392662507;9826.65606703762;588.9734961926723;639.8656282180795;656.9128093006083;665.6139439125295;683.4771399879096;4275.230458517434;4341.283688191476;4730.937896450765;4749.501302363202;5025.396952365666;113522;114772.42613787044;115947.84052159371;116031.41984290024;116158;89999;92902.0709792907;93121;94358.5282073599;94407.52796235975;84174.57912710398;88556.5572172135;90991;91228.5438572803;91772.54113729254;8855;8884.644614215358;8903.3322500814;8983.907269303741;8986.14631609996;45385.54614453813;46892.29661555011;50978.74510627423;51210.743946281524;54227.72886135544;70234.29765702278;73660;74091.25908740844;74733.626331868;77105.22894770981;13094.410751516014;13275.66810829737;13294.40175192138;13341.733165336833;13782.93108534451;14970.326335315154;15141.848581514043;15232.77150842716;15302.693946120793;15399;14378.784318235026;14726.337314820645;14983.325750341479;14992.925035374754;15457.768133478232;14728;14922.104673719612;15088.849111509184;15215.847841521885;15400.306986185424;13471.865281347062;14498.782518262024;14575.44929637288;14665.70668586601;15046.849531504546;11157.21899467062;11318.36048834412;11457.427128643369;11617.6514704559;11795.407619971536;10371.117446464348;10947.299097453923;11129.443527823738;11639.883601164218;11683.532658693557 07:42:20 INFO - PROCESS | 2458 | __end_tp_report 07:42:20 INFO - PROCESS | 2458 | __start_cc_report 07:42:20 INFO - PROCESS | 2458 | _x_x_mozilla_cycle_collect,1026 07:42:20 INFO - PROCESS | 2458 | __end_cc_report 07:42:20 INFO - PROCESS | 2458 | __startTimestamp1466174540626__endTimestamp 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | ------- Summary: start ------- 07:42:20 INFO - PROCESS | 2458 | Number of tests: 6 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | [#0] dojo.html Cycles:165 Average:5350.62 Median:5523.70 stddev:591.22 (10.7%) stddev-sans-first:566.41 07:42:20 INFO - PROCESS | 2458 | Values: 3114.2 3129.6 3137.4 3188.5 3191.3 5275.9 5311.9 5338.9 5467.5 5497.4 5286.9 5327.8 5346.1 5375.7 5411.1 4806.5 4856.7 4898.6 4899.9 5071.6 4971.2 4994.3 5024.3 5055.1 5060.9 4792.7 4816.2 4833.9 4877.0 4928.5 4865.4 4901.3 4904.8 4952.6 5004.6 4905.0 4928.5 4937.8 4950.2 4985.5 5191.6 5287.3 5385.8 5407.0 5432.3 5765.1 5804.9 5821.7 5848.6 5857.0 5599.6 5861.4 5864.8 5878.7 5883.3 5213.2 5216.3 5266.9 5337.3 5340.3 5812.5 5818.7 5821.1 5825.1 5839.7 5475.8 5602.4 5713.0 5734.7 5741.3 5704.7 5811.4 5850.7 5857.2 5858.6 5763.3 5776.5 5780.5 5781.4 5783.2 5503.6 5728.2 5796.3 5798.9 5799.4 4358.9 4397.1 4456.5 4470.5 4480.8 3981.2 3984.2 3990.0 4018.2 4023.5 5248.4 5505.6 5507.5 5521.6 5523.7 5696.4 5706.1 5774.5 5782.5 5804.6 5465.9 5469.8 5470.0 5471.3 5475.5 4904.6 4925.0 5033.3 5042.2 5087.2 5856.5 5918.5 5933.2 5940.0 5989.4 5731.7 5745.3 5750.1 5751.7 5753.7 5821.2 5833.4 5898.7 5903.4 5907.4 5715.1 5717.4 5725.1 5725.9 5766.4 5735.0 5759.3 5792.6 5811.9 5819.9 5416.1 5534.7 5643.7 5646.7 5648.9 5627.5 5633.3 5635.9 5642.7 5648.0 5491.2 5537.2 5538.4 5539.8 5542.9 5451.5 5511.3 5553.2 5578.4 5660.6 5459.3 5484.7 5547.4 5566.3 5568.3 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | [#1] ext.html Cycles:180 Average:54691.18 Median:40765.80 stddev:105029.98 (257.6%) stddev-sans-first:105246.04 07:42:20 INFO - PROCESS | 2458 | Values: 579.3 608.9 611.6 618.9 631.5 30182.0 30728.8 32923.3 32932.8 33018.7 32838.2 43343.3 44023.6 44212.3 48228.5 8798.8 8905.7 8913.5 8917.8 8919.8 8745.1 8797.9 8837.9 8846.3 8847.1 8930.7 8960.8 8966.5 8968.4 8969.6 8844.9 8918.8 8930.4 8957.5 8962.6 8795.8 8841.5 8863.1 8863.4 8870.9 42438.8 42695.6 42890.4 43022.4 43142.6 3491.2 3645.3 3919.1 4039.6 4392.8 31913.4 32543.8 32894.8 32918.8 33095.0 4347.2 4353.6 4424.8 4473.9 4590.6 226375.0 228493.0 229667.0 229829.0 230309.0 57802.0 58038.4 58194.7 58244.4 58273.7 60683.4 60696.0 60698.0 60759.7 60815.4 16969.3 17265.2 17284.0 17289.7 17291.8 13385.9 13517.2 13568.5 13570.1 13578.5 15543.2 16285.2 16355.4 16365.9 16511.2 5825.4 5882.9 5986.4 6101.2 6129.8 999.0 1009.3 1074.0 1094.1 1120.6 31144.4 31564.7 31870.5 31959.7 32478.8 1121.4 1127.5 1180.0 1208.9 1274.7 16566.2 16687.4 16700.9 16814.7 17082.3 515752.0 516981.0 517544.0 520606.0 523915.0 57216.7 57299.0 57370.1 57373.0 57460.0 1260.7 1336.8 1362.0 1387.1 1393.4 31537.2 32604.5 32710.0 33082.6 33225.0 49337.5 49838.3 50181.5 50191.2 50192.2 383661.0 383810.0 385270.0 385793.0 385826.0 33877.6 34228.3 35253.0 41381.6 41849.8 38271.2 45934.9 47190.8 48086.8 48630.5 39502.8 40549.6 40799.0 41827.0 42662.8 39744.8 40256.4 40461.8 40867.6 41208.6 40286.8 40697.6 40732.6 41425.8 41559.0 34243.5 35508.3 36506.0 36577.3 36606.8 26856.5 27349.0 27718.7 27819.3 27867.9 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | [#2] jquery.html Cycles:180 Average:29199.63 Median:2615.70 stddev:81776.61 (3126.4%) stddev-sans-first:81977.34 07:42:20 INFO - PROCESS | 2458 | Values: 357.3 371.8 376.8 386.8 405.1 17785.1 19087.9 19813.7 20287.4 20294.6 17935.8 18517.0 18595.4 18657.9 18861.2 5575.2 5601.5 5753.0 5799.9 5854.6 3895.3 5551.4 5804.7 5828.0 5837.7 5616.8 5862.8 5875.5 5875.6 5885.8 5695.3 5718.5 5741.4 5749.7 5751.9 5524.6 5578.4 5587.0 5591.2 5828.2 12612.1 12618.8 12809.8 12843.1 12939.5 2173.8 2358.7 2460.7 2477.3 2754.1 9773.7 9834.4 10132.7 10532.3 10559.3 1881.3 1925.4 1991.3 1993.5 2021.0 79803.0 80889.0 84614.2 85091.0 85157.6 10882.0 10996.6 11007.8 11080.7 11176.1 14922.1 15013.8 15069.7 15087.0 15105.5 8072.5 8234.4 8251.1 8376.7 8390.6 8145.8 8174.8 8180.3 8333.4 8341.8 4679.7 4773.7 4783.2 4789.4 4876.8 1746.7 1755.8 1917.5 1947.2 2013.7 911.3 1012.1 1024.6 1055.8 1065.2 18791.7 19143.5 20455.9 20643.2 20671.2 1045.0 1051.6 1052.5 1057.0 1073.7 10384.0 10905.2 10935.3 10979.7 11027.0 486943.0 494624.0 494960.0 496552.0 500226.0 95517.0 99055.0 99556.5 99746.0 99748.5 977.9 991.3 991.7 994.4 1033.8 16025.4 17184.0 17418.7 17440.6 17968.9 43163.0 43427.0 43700.0 44156.1 44955.3 57405.4 57918.4 58769.4 58858.4 59686.7 7865.2 7976.9 8062.1 8074.6 9022.4 8971.0 12950.8 13297.9 13592.4 13660.8 11887.6 12171.7 12318.4 12473.1 12549.1 11878.3 12359.3 12363.6 12370.3 12673.4 12444.8 12486.2 12611.9 12841.6 12866.7 7656.0 7984.8 8935.7 9048.3 9177.1 10874.5 11154.2 11638.4 11779.1 12143.9 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | [#3] mootools.html Cycles:180 Average:5881.29 Median:4789.57 stddev:3421.53 (71.4%) stddev-sans-first:3413.90 07:42:20 INFO - PROCESS | 2458 | Values: 1311.5 1442.7 1479.7 1606.4 1776.0 8215.5 8651.0 8655.4 9158.6 9167.9 8842.3 9077.7 9081.0 9087.5 9094.6 2054.4 2111.8 2114.0 2325.9 2353.7 2302.1 2383.5 2384.4 2398.7 2402.1 2180.1 2215.4 2221.6 2241.3 2250.0 2011.8 2231.4 2265.6 2270.4 2272.3 2326.3 2347.9 2371.8 2372.4 2373.5 8663.7 8715.3 8725.9 8728.3 8733.3 5114.6 5236.1 5237.7 5241.8 5244.6 3183.4 3331.8 3396.5 3402.4 3406.9 1091.5 1105.6 1110.5 1124.2 1159.0 11973.5 12203.8 12256.4 12925.4 12973.3 4637.7 4652.7 4772.3 4785.4 4793.7 10111.3 10120.3 10122.0 10128.7 10137.3 5339.2 5371.6 5373.4 5375.8 5384.8 4362.8 4745.1 4893.5 4929.2 4982.9 3874.3 3894.1 3967.5 3988.8 3999.0 1110.3 1114.3 1126.2 1141.2 1162.6 909.9 915.4 919.7 928.5 941.8 5608.9 5701.2 5752.7 5886.2 6016.2 882.4 888.3 965.6 986.3 990.8 3743.5 3891.2 3900.6 3937.2 4029.4 11104.9 11363.3 11460.9 11561.3 11612.1 11421.1 11430.0 11449.3 11459.7 11478.5 7998.0 8390.5 8542.2 8542.4 8547.1 5859.5 5898.9 5909.2 6067.7 6090.7 9782.6 10195.2 10520.8 10540.0 10555.0 10316.3 10320.7 10325.4 10338.2 10722.4 8084.6 8290.7 8353.6 8357.3 8368.9 8804.3 8806.9 8843.6 8866.0 8875.0 8095.9 8104.7 8116.4 8119.5 8296.0 7895.7 7901.3 7925.7 7935.6 8121.7 7085.5 7109.6 7136.1 7149.5 7162.1 4758.0 4957.2 5054.0 5090.4 5228.3 5143.8 5164.1 5186.7 5253.3 5276.5 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | [#4] prototype.html Cycles:180 Average:4645.31 Median:5223.78 stddev:1377.22 (26.4%) stddev-sans-first:1341.91 07:42:20 INFO - PROCESS | 2458 | Values: 299.8 314.4 318.5 325.1 327.6 5178.8 5219.8 5342.4 5386.4 5396.0 5267.2 5324.7 5327.8 5347.6 5348.1 4181.4 4184.4 4188.3 4191.0 4202.6 3908.8 3973.9 4154.8 4193.5 4195.6 4049.5 4080.0 4082.8 4128.4 4134.6 4102.8 4133.2 4149.8 4170.4 4171.4 3431.8 3679.6 3682.2 3683.5 3687.6 5165.2 5186.1 5197.3 5201.8 5227.8 4663.1 4673.8 4680.2 4691.2 4692.0 5453.2 5544.4 5559.7 5583.7 5599.5 3893.4 3930.3 3938.6 3945.4 3966.4 5795.7 5819.4 5825.1 5831.5 5847.3 3929.7 4053.5 4077.7 4088.6 4095.4 4117.0 4124.4 4130.6 4135.3 4146.0 4713.2 4729.7 4734.9 4748.0 4751.3 5526.1 5617.5 5634.1 5689.9 5694.9 1410.4 1412.8 1421.8 1425.6 1432.1 874.7 882.7 887.2 891.9 912.8 4763.5 4802.9 4814.2 4815.0 4851.8 4993.1 5009.5 5019.3 5044.4 5077.7 4768.4 4804.4 4809.4 4812.4 4970.3 3054.7 3056.2 3061.2 3070.9 3071.7 5879.6 5919.7 5921.9 5923.8 5928.3 5593.6 5874.5 5883.0 5896.7 5906.3 5924.2 5935.3 5944.1 5962.4 5965.0 5554.8 5725.0 5764.8 5767.6 5774.8 5687.5 5711.7 5713.3 5718.4 5726.0 5928.0 5933.2 5938.0 5969.4 5980.3 3733.6 3801.4 4058.6 4064.1 4069.8 5639.4 5669.5 5685.9 5688.6 5708.7 5502.0 5512.0 5542.4 5551.8 5559.9 5309.6 5417.5 5568.1 5646.0 5653.2 5453.8 5464.3 5471.5 5498.3 5506.9 5366.6 5662.3 5676.2 5685.7 5702.3 5481.3 5507.6 5512.9 5534.9 5558.0 07:42:20 INFO - PROCESS | 2458 | 07:42:20 INFO - PROCESS | 2458 | [#5] yui.html Cycles:180 Average:20852.85 Median:4814.85 stddev:29483.14 (612.3%) stddev-sans-first:29527.16 07:42:20 INFO - PROCESS | 2458 | Values: 736.8 767.7 770.0 792.5 793.2 15349.5 15909.4 16480.0 16545.8 16570.2 13956.9 14002.5 14043.6 14075.9 14092.2 656.2 774.8 796.0 807.9 808.3 790.9 799.0 799.0 799.4 800.8 841.3 847.3 848.2 848.3 848.3 687.9 810.0 837.8 842.7 843.9 615.8 616.5 616.7 616.8 617.0 15721.0 15727.4 15743.1 15750.9 15808.0 14393.9 14832.6 14859.1 14877.2 15028.8 12837.6 12999.8 13143.3 13163.2 13353.7 4494.7 4624.4 4708.9 4727.7 4731.0 73310.6 73834.6 74157.6 74572.6 74674.6 11689.5 11694.5 11721.5 11815.7 11942.6 22113.2 22293.6 22299.9 22318.0 22684.1 8780.4 8793.5 8846.8 8953.9 9342.3 4880.2 4899.9 4942.9 4949.7 5089.7 3492.0 3605.1 3606.7 3608.4 3611.3 605.1 615.0 619.5 626.6 633.9 572.5 620.3 669.4 702.6 713.7 9200.0 9460.1 9484.3 9515.3 9826.7 589.0 639.9 656.9 665.6 683.5 4275.2 4341.3 4730.9 4749.5 5025.4 113522.0 114772.4 115947.8 116031.4 116158.0 89999.0 92902.1 93121.0 94358.5 94407.5 84174.6 88556.6 90991.0 91228.5 91772.5 8855.0 8884.6 8903.3 8983.9 8986.1 45385.5 46892.3 50978.7 51210.7 54227.7 70234.3 73660.0 74091.3 74733.6 77105.2 13094.4 13275.7 13294.4 13341.7 13782.9 14970.3 15141.8 15232.8 15302.7 15399.0 14378.8 14726.3 14983.3 14992.9 15457.8 14728.0 14922.1 15088.8 15215.8 15400.3 13471.9 14498.8 14575.4 14665.7 15046.8 11157.2 11318.4 11457.4 11617.7 11795.4 10371.1 10947.3 11129.4 11639.9 11683.5 07:42:20 INFO - PROCESS | 2458 | -------- Summary: end -------- 07:42:20 INFO - PROCESS | 2458 | 07:42:21 INFO - TEST-INFO | 2458: exit 0 07:42:21 INFO - TEST-OK | dromaeo_css | took 1114838ms 07:42:21 INFO - TEST-START | kraken 07:42:21 INFO - operating with platform_type : linux_ 07:42:21 INFO - Initialising browser for kraken test... 07:42:21 INFO - TEST-INFO | started process 2717 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsh6rQQ/profile http://localhost:50089/getInfo.html) 07:42:25 INFO - PROCESS | 2717 | __metrics Screen width/height:1600/1200 07:42:25 INFO - PROCESS | 2717 | colorDepth:24 07:42:25 INFO - PROCESS | 2717 | Browser inner width/height: 1024/697 07:42:25 INFO - PROCESS | 2717 | __metrics 07:42:26 INFO - PROCESS | 2717 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:42:27 INFO - PROCESS | 2717 | console.error: PushService: 07:42:27 INFO - PROCESS | 2717 | stateChangeProcessEnqueue: Error transitioning state 07:42:27 INFO - PROCESS | 2717 | UnknownError 07:42:27 INFO - TEST-INFO | 2717: exit 0 07:42:27 INFO - Browser initialized. 07:42:27 INFO - Running cycle 1/1 for kraken test... 07:42:27 INFO - TEST-INFO | started process 2877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsh6rQQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:42:39 INFO - PROCESS | 2877 | RSS: Main: 173654016 07:42:39 INFO - PROCESS | 2877 | 07:42:42 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50089/tests/kraken/driver.html?testName=audio-beat-detection) 07:42:42 INFO - PROCESS | 2877 | RSS: Main: 233426944 07:42:42 INFO - PROCESS | 2877 | 07:42:45 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50089/tests/kraken/driver.html?testName=audio-dft) 07:42:46 INFO - PROCESS | 2877 | RSS: Main: 216981504 07:42:46 INFO - PROCESS | 2877 | 07:42:49 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50089/tests/kraken/driver.html?testName=audio-fft) 07:42:50 INFO - PROCESS | 2877 | RSS: Main: 287412224 07:42:50 INFO - PROCESS | 2877 | 07:42:52 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50089/tests/kraken/driver.html?testName=audio-oscillator) 07:42:52 INFO - PROCESS | 2877 | RSS: Main: 198172672 07:42:52 INFO - PROCESS | 2877 | 07:42:54 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50089/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:42:55 INFO - PROCESS | 2877 | RSS: Main: 201785344 07:42:55 INFO - PROCESS | 2877 | 07:42:58 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50089/tests/kraken/driver.html?testName=imaging-darkroom) 07:42:59 INFO - PROCESS | 2877 | RSS: Main: 238694400 07:42:59 INFO - PROCESS | 2877 | 07:43:02 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50089/tests/kraken/driver.html?testName=imaging-desaturate) 07:43:03 INFO - PROCESS | 2877 | RSS: Main: 239001600 07:43:03 INFO - PROCESS | 2877 | 07:43:05 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50089/tests/kraken/driver.html?testName=json-parse-financial) 07:43:06 INFO - PROCESS | 2877 | RSS: Main: 238747648 07:43:06 INFO - PROCESS | 2877 | 07:43:08 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50089/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:43:08 INFO - PROCESS | 2877 | RSS: Main: 227262464 07:43:08 INFO - PROCESS | 2877 | 07:43:10 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:43:11 INFO - PROCESS | 2877 | RSS: Main: 229691392 07:43:11 INFO - PROCESS | 2877 | 07:43:13 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:43:13 INFO - PROCESS | 2877 | RSS: Main: 237502464 07:43:13 INFO - PROCESS | 2877 | 07:43:16 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:43:17 INFO - PROCESS | 2877 | RSS: Main: 239755264 07:43:17 INFO - PROCESS | 2877 | 07:43:19 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:43:20 INFO - PROCESS | 2877 | RSS: Main: 240594944 07:43:20 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:50089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50089/tests/kraken/driver.html?testName=ai-astar) 07:43:22 INFO - PROCESS | 2877 | RSS: Main: 283172864 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | __start_tp_report 07:43:22 INFO - PROCESS | 2877 | _x_x_mozilla_page_load 07:43:22 INFO - PROCESS | 2877 | _x_x_mozilla_page_load_details 07:43:22 INFO - PROCESS | 2877 | |i|pagename|runs| 07:43:22 INFO - PROCESS | 2877 | |0;ai-astar;110;106;110;110;110;110;109;108;109;109 07:43:22 INFO - PROCESS | 2877 | |1;audio-beat-detection;161;133;137;187;133;150;159;127;201;132 07:43:22 INFO - PROCESS | 2877 | |2;audio-dft;163;163;162;161;161;161;162;160;162;160 07:43:22 INFO - PROCESS | 2877 | |3;audio-fft;75;75;74;101;75;76;73;74;113;76 07:43:22 INFO - PROCESS | 2877 | |4;audio-oscillator;86;85;107;86;85;86;116;85;86;133 07:43:22 INFO - PROCESS | 2877 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;116;117 07:43:22 INFO - PROCESS | 2877 | |6;imaging-darkroom;144;145;145;145;145;145;145;146;145;145 07:43:22 INFO - PROCESS | 2877 | |7;imaging-desaturate;94;94;94;95;94;94;94;95;95;95 07:43:22 INFO - PROCESS | 2877 | |8;json-parse-financial;52;55;53;52;51;52;54;53;52;52 07:43:22 INFO - PROCESS | 2877 | |9;json-stringify-tinderbox;62;64;63;63;64;63;63;63;63;64 07:43:22 INFO - PROCESS | 2877 | |10;stanford-crypto-aes;82;79;80;79;81;81;81;83;112;81 07:43:22 INFO - PROCESS | 2877 | |11;stanford-crypto-ccm;123;123;125;130;128;119;120;131;137;135 07:43:22 INFO - PROCESS | 2877 | |12;stanford-crypto-pbkdf2;167;164;169;160;164;159;164;163;161;157 07:43:22 INFO - PROCESS | 2877 | |13;stanford-crypto-sha256-iterative;64;63;64;63;67;63;63;62;61;65 07:43:22 INFO - PROCESS | 2877 | __end_tp_report 07:43:22 INFO - PROCESS | 2877 | __start_cc_report 07:43:22 INFO - PROCESS | 2877 | _x_x_mozilla_cycle_collect,1674 07:43:22 INFO - PROCESS | 2877 | __end_cc_report 07:43:22 INFO - PROCESS | 2877 | __startTimestamp1466174602263__endTimestamp 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | ------- Summary: start ------- 07:43:22 INFO - PROCESS | 2877 | Number of tests: 14 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:1.29 (1.2%) stddev-sans-first:1.32 07:43:22 INFO - PROCESS | 2877 | Values: 110.0 106.0 110.0 110.0 110.0 110.0 109.0 108.0 109.0 109.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#1] audio-beat-detection Cycles:10 Average:152.00 Median:154.50 stddev:25.24 (16.3%) stddev-sans-first:26.56 07:43:22 INFO - PROCESS | 2877 | Values: 161.0 133.0 137.0 187.0 133.0 150.0 159.0 127.0 201.0 132.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 07:43:22 INFO - PROCESS | 2877 | Values: 163.0 163.0 162.0 161.0 161.0 161.0 162.0 160.0 162.0 160.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:13.92 (18.6%) stddev-sans-first:14.58 07:43:22 INFO - PROCESS | 2877 | Values: 75.0 75.0 74.0 101.0 75.0 76.0 73.0 74.0 113.0 76.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:85.50 stddev:17.16 (20.1%) stddev-sans-first:17.85 07:43:22 INFO - PROCESS | 2877 | Values: 86.0 85.0 107.0 86.0 85.0 86.0 116.0 85.0 86.0 133.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:43:22 INFO - PROCESS | 2877 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 07:43:22 INFO - PROCESS | 2877 | Values: 144.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:43:22 INFO - PROCESS | 2877 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.50 stddev:1.17 (2.2%) stddev-sans-first:1.22 07:43:22 INFO - PROCESS | 2877 | Values: 52.0 55.0 53.0 52.0 51.0 52.0 54.0 53.0 52.0 52.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 07:43:22 INFO - PROCESS | 2877 | Values: 62.0 64.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 64.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:81.00 stddev:9.95 (12.3%) stddev-sans-first:10.53 07:43:22 INFO - PROCESS | 2877 | Values: 82.0 79.0 80.0 79.0 81.0 81.0 81.0 83.0 112.0 81.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:129.00 stddev:6.14 (4.8%) stddev-sans-first:6.33 07:43:22 INFO - PROCESS | 2877 | Values: 123.0 123.0 125.0 130.0 128.0 119.0 120.0 131.0 137.0 135.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:164.00 stddev:3.65 (2.2%) stddev-sans-first:3.54 07:43:22 INFO - PROCESS | 2877 | Values: 167.0 164.0 169.0 160.0 164.0 159.0 164.0 163.0 161.0 157.0 07:43:22 INFO - PROCESS | 2877 | 07:43:22 INFO - PROCESS | 2877 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.50 stddev:1.65 (2.6%) stddev-sans-first:1.74 07:43:22 INFO - PROCESS | 2877 | Values: 64.0 63.0 64.0 63.0 67.0 63.0 63.0 62.0 61.0 65.0 07:43:22 INFO - PROCESS | 2877 | -------- Summary: end -------- 07:43:22 INFO - PROCESS | 2877 | 07:43:23 INFO - TEST-INFO | 2877: exit 0 07:43:23 INFO - TEST-OK | kraken | took 61988ms 07:43:23 INFO - SUITE-END | took 1176s 07:43:23 INFO - Completed test suite (00:19:36) 07:43:23 INFO - javascript benchmark 07:43:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.1591770222035, 3129.593152890124, 3137.4038932602803, 3188.521721741737, 3191.250056236784, 5275.947240527603, 5311.9468805311935, 5338.919916201256, 5467.535259502942, 5497.367802702685, 5286.894262114755, 5327.786888524454, 5346.064438723227, 5375.650582712121, 5411.107167317388, 4806.471288158308, 4856.708597484145, 4898.608111351101, 4899.926501102486, 5071.56891664209, 4971.204607262839, 4994.300797888299, 5024.271480635306, 5055.090083784918, 5060.898782024357, 4792.7124372537755, 4816.181249187629, 4833.879153021167, 4877.0489754497885, 4928.531789479994, 4865.367502224688, 4901.289313049623, 4904.779284932187, 4952.5542701157065, 5004.574611158056, 4905.0, 4928.507149285078, 4937.827176048857, 4950.232713929347, 4985.451600323998, 5191.558717509016, 5287.312649355597, 5385.8114965976, 5406.972965135188, 5432.266644003049, 5765.106408506688, 5804.912926306109, 5821.67980761058, 5848.649081055108, 5857.033589457735, 5599.636023658449, 5861.384554621789, 5864.76540938362, 5878.706064696748, 5883.264591926009, 5213.165893457029, 5216.347956505437, 5266.868328291824, 5337.306150200527, 5340.25236466895, 5812.476877081083, 5818.679972601463, 5821.126830975388, 5825.0679891217205, 5839.678817664984, 5475.835724928259, 5602.411746766612, 5712.971435142881, 5734.6845923473775, 5741.282339707536, 5704.657720536698, 5811.4479124483105, 5850.736716847667, 5857.238558987305, 5858.589898707135, 5763.250777398911, 5776.5089967352405, 5780.53755699543, 5781.392953739881, 5783.2192653992015, 5503.642263252876, 5728.226689396878, 5796.333421656479, 5798.9130163047585, 5799.420057994167, 4358.934615980763, 4397.07661391105, 4456.509783923766, 4470.530594287618, 4480.753558554311, 3981.243563722883, 3984.242993831163, 3989.960100399017, 4018.176273863851, 4023.5171779386073, 5248.396434410091, 5505.642133261325, 5507.476789704971, 5521.641093328921, 5523.696196709139, 5696.3733989261145, 5706.115552089433, 5774.48029677331, 5782.508486778671, 5804.564657650693, 5465.9180112298345, 5469.835904922858, 5470.0, 5471.316085489314, 5475.534579560703, 4904.60763138948, 4925.0, 5033.295338652591, 5042.193249080129, 5087.211482220262, 5856.502197313191, 5918.496927761103, 5933.169356290123, 5940.0, 5989.371116032841, 5731.742071606871, 5745.339285982165, 5750.079987202028, 5751.654900705971, 5753.71231438418, 5821.185034095145, 5833.445822646841, 5898.705064746659, 5903.439173278532, 5907.379725128972, 5715.142728590745, 5717.42825717425, 5725.112607545837, 5725.914111288417, 5766.423357664368, 5734.971325143348, 5759.337676167127, 5792.623479473821, 5811.854703632443, 5819.912701309399, 5416.079266524622, 5534.695591742493, 5643.71781410936, 5646.7458964347525, 5648.915266270927, 5627.493525582553, 5633.295838020247, 5635.943640563706, 5642.746076426489, 5648.039833228443, 5491.203775452457, 5537.169424586184, 5538.363088244903, 5539.778408863762, 5542.9168562472405, 5451.536619387396, 5511.338639363302, 5553.222548843084, 5578.386377498391, 5660.575456840836, 5459.2902922619805, 5484.698341591251, 5547.44525547442, 5566.304211973504, 5568.303962004749], "value": 5312.000386093248, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.3482332376077, 608.8934436473613, 611.6452457574608, 618.9442950134487, 631.4506379449878, 30182.0, 30728.84635576822, 32923.341533169325, 32932.83533582335, 33018.66981330186, 32838.179045523815, 43343.34984975228, 44023.559764402344, 44212.336814947725, 48228.51771482284, 8798.824023519543, 8905.688300909469, 8913.509756963364, 8917.77705557363, 8919.821603567925, 8745.08176641453, 8797.912020879805, 8837.867431988494, 8846.292296616255, 8847.115288471166, 8930.687425940094, 8960.775980600505, 8966.506842123681, 8968.372213945026, 8969.641214351419, 8844.894388201476, 8918.821623567525, 8930.419522731001, 8957.507337096456, 8962.59668314924, 8795.78010549735, 8841.469511829246, 8863.069377715312, 8863.379563430562, 8870.86693699595, 42438.78780606108, 42695.57304426978, 42890.356644650354, 43022.35466468005, 43142.568574314486, 3491.17957280039, 3645.343838109139, 3919.0790164311366, 4039.575844536311, 4392.758398288093, 31913.361732765214, 32543.837280813445, 32894.835525822455, 32918.835405822814, 33095.0, 4347.217500849846, 4353.564643535653, 4424.765567929399, 4473.865784026484, 4590.632749380042, 226375.0, 228493.0, 229667.0, 229829.0, 230309.0, 57802.0, 58038.4196158033, 58194.7090264546, 58244.417555824744, 58273.70863145742, 60683.39316606778, 60696.0, 60698.0, 60759.69620151871, 60815.39184608186, 16969.321227150776, 17265.223064962105, 17284.0, 17289.740653890203, 17291.82708172902, 13385.933070334586, 13517.188968661912, 13568.525101621395, 13570.117942333716, 13578.524751633646, 15543.222838858239, 16285.185740713154, 16355.427560035341, 16365.918170409072, 16511.17444127813, 5825.38833422493, 5882.911756323659, 5986.431289027535, 6101.206843110367, 6129.816105516842, 998.9860291803825, 1009.3388830316155, 1073.951672174754, 1094.0973696700096, 1120.618989543559, 31144.377112457623, 31564.684353156634, 31870.521942170886, 31959.680403196136, 32478.837605812292, 1121.4224674292745, 1127.5489804078434, 1179.979317890036, 1208.9214201076902, 1274.7323062156865, 16566.17169141538, 16687.415940442028, 16700.916495417445, 16814.710380662662, 17082.316707331815, 515752.0, 516981.0, 517544.0, 520606.0, 523915.0, 57216.71391643015, 57299.0, 57370.13944790832, 57373.0, 57460.0, 1260.7415479826614, 1336.7927971164486, 1361.9795703064462, 1387.0914550969333, 1393.4356585582855, 31537.21156971094, 32604.51093233556, 32710.0, 33082.64665170902, 33225.0, 49337.50662493473, 49838.25242621436, 50181.49818501914, 50191.24713129233, 50192.24711629401, 383661.0, 383810.0, 385270.0, 385793.0, 385826.0, 33877.62856600113, 34228.315433691685, 35253.0, 41381.58618413777, 41849.79075104605, 38271.23457530778, 45934.88844925228, 47190.76404617955, 48086.759566201945, 48630.513694862595, 39502.802485987384, 40549.59450405576, 40799.0, 41827.0, 42662.78668606637, 39744.801275993435, 40256.396154057125, 40461.79769101136, 40867.59132408757, 41208.58791412167, 40286.79856600698, 40697.59302406938, 40732.59267407288, 41425.79287103545, 41559.0, 34243.4863477053, 35508.289834203686, 36506.0, 36577.268454630226, 36606.81696591606, 26856.462870742867, 27349.0, 27718.72281277242, 27819.30451738642, 27867.860660696566], "value": 19691.769229444508, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.2996926024989, 371.7825072332685, 376.7875155758619, 386.79499865071506, 405.08652987513153, 17785.110744462774, 19087.904560477196, 19813.70279445806, 20287.391378258657, 20294.594108117864, 17935.82064179358, 18517.0, 18595.442136735885, 18657.90671046643, 18861.15124819383, 5575.191597218401, 5601.467860553241, 5753.0, 5799.855003624901, 5854.560907931901, 3895.2988462076814, 5551.417101204356, 5804.738786754606, 5828.0, 5837.737301821407, 5616.775328986836, 5862.794802181925, 5875.471207591316, 5875.588708790387, 5885.793997210077, 5695.288088988877, 5718.485336319731, 5741.397153298885, 5749.741261643236, 5751.913721294184, 5524.640898341595, 5578.44215578443, 5587.0, 5591.161325801122, 5828.2131912191735, 12612.117151799377, 12618.81071783924, 12809.807852882213, 12843.100982931199, 12939.48242070316, 2173.793489618484, 2358.740538540759, 2460.667809845666, 2477.2692055843568, 2754.132448278786, 9773.706788796277, 9834.409935403826, 10132.696019119438, 10532.315399499008, 10559.313644613076, 1881.3039175505014, 1925.4031250312448, 1991.2831380702935, 1993.451800754804, 2020.9592060089067, 79803.0, 80889.0, 84614.15385846124, 85090.99999999937, 85157.57421212917, 10882.0, 10996.560137594566, 11007.834882476769, 11080.667579972614, 11176.05003574689, 14922.104673719394, 15013.774793378108, 15069.698606027818, 15087.0, 15105.47130850415, 8072.515649061075, 8234.423590348619, 8251.092379838214, 8376.664933402717, 8390.622421991025, 8145.755627331189, 8174.7956301092945, 8180.263776260165, 8333.416660833806, 8341.791455213668, 4679.67242293043, 4773.737444440589, 4783.23468245086, 4789.425268967746, 4876.780544875489, 1746.7117925542398, 1755.8249325233037, 1917.511034686174, 1947.2113793913488, 2013.677811550145, 911.2755359489222, 1012.0587853296505, 1024.5850430575629, 1055.7835643693024, 1065.169168048923, 18791.718124228148, 19143.521411964535, 20455.8977205116, 20643.17427302891, 20671.17315307371, 1045.0437849367743, 1051.558345494894, 1052.4950617530228, 1056.99471502642, 1073.6778966310076, 10384.0, 10905.18211134168, 10935.343879367105, 10979.725506862393, 11027.0, 486943.0, 494624.0, 494960.0, 496552.0, 500226.0, 95517.0, 99055.0, 99556.50221748989, 99746.0, 99748.50125749325, 977.8630991661173, 991.2912267728465, 991.7223177510165, 994.4232345239743, 1033.7570670892485, 16025.358985640443, 17184.0, 17418.738718918972, 17440.563985900455, 17968.91015544914, 43163.0, 43427.0, 43700.0, 44156.116877661625, 44955.32567011563, 57405.42594574001, 57918.42081579299, 58769.412305876394, 58858.41141588529, 59686.70156649189, 7865.213478652089, 7976.880346794686, 8062.113167551682, 8074.636641351271, 9022.413543119676, 8970.955145224232, 12950.80573791375, 13297.933510332774, 13592.388342524808, 13660.795088073886, 11887.643370698894, 12171.695707607381, 12318.384080795746, 12473.126881118234, 12549.121561490609, 11878.287302761863, 12359.25844449336, 12363.62909112728, 12370.319632420304, 12673.366331683195, 12444.813327800272, 12486.188397754117, 12611.93694031524, 12841.550545730855, 12866.67833304175, 7656.0, 7984.800379990546, 8935.687250946447, 9048.321375896965, 9177.11869066853, 10874.510647021061, 11154.219204655596, 11638.418079095842, 11779.116566257737, 12143.939280303543], "value": 9145.530209704462, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.5278499740086, 1442.7186698593775, 1479.6596782739969, 1606.4377467886234, 1775.9911200443996, 8215.548144852031, 8650.956745216274, 8655.43739656922, 9158.58786354614, 9167.90832091679, 8842.292616590674, 9077.72766816995, 9081.0, 9087.500187489686, 9094.636214551427, 2054.352878843167, 2111.788821117884, 2113.9788602113936, 2325.8604483730946, 2353.6587194856734, 2302.0791683326634, 2383.475635360218, 2384.391980045095, 2398.7121545414575, 2402.147237730598, 2180.051677520276, 2215.4239897626653, 2221.555688862226, 2241.3051953894346, 2249.9662505062356, 2011.7787043425212, 2231.3752149398197, 2265.6148454762647, 2270.3642979965553, 2272.3069463813613, 2326.302109367183, 2347.882605869717, 2371.845830021043, 2372.4424760181346, 2373.5252949410187, 8663.653453861838, 8715.346349023848, 8725.912740872573, 8728.301735861116, 8733.25767309773, 5114.616403769732, 5236.057509648262, 5237.711925844077, 5241.79032838686, 5244.60665450089, 3183.3951549205576, 3331.833408329574, 3396.456566949301, 3402.3705614461155, 3406.9488957665653, 1091.5088210305394, 1105.6461932181705, 1110.5169251375678, 1124.1905827803969, 1159.0495793449375, 11973.46119424628, 12203.755924881452, 12256.387180640932, 12925.418356173901, 12973.286469244187, 4637.744924029211, 4652.674312798072, 4772.331873537708, 4785.449673287547, 4793.688410253322, 10111.292209545261, 10120.291579589506, 10122.0, 10128.746781330377, 10137.341072830242, 5339.172428273546, 5371.57027437804, 5373.35519737634, 5375.784968601212, 5384.78460861569, 4362.76004819738, 4745.050989802054, 4893.486183950705, 4929.211326187793, 4982.9252561211615, 3874.3219936511, 3894.1432884765304, 3967.5238971323624, 3988.780617066031, 3999.0402303446963, 1110.3337997201616, 1114.2979922648678, 1126.2116518437126, 1141.1897552737482, 1162.5526219856333, 909.9454032758056, 915.4370062411568, 919.6919032124314, 928.4614923344444, 941.8257622339889, 5608.887822243532, 5701.177916931063, 5752.741126649311, 5886.234789477427, 6016.217891674142, 882.4043770455046, 888.3159966825508, 965.6185806606351, 986.2603047714215, 990.8266053440764, 3743.5320584926885, 3891.2412079644764, 3900.551436584829, 3937.1928754605237, 4029.3955906613655, 11104.888951110546, 11363.318200907972, 11460.885391146148, 11561.30632162073, 11612.12909031826, 11421.086313094933, 11430.028447581883, 11449.313041217554, 11459.656210313704, 11478.540858365572, 7998.040235171817, 8390.496570205807, 8542.18849209324, 8542.44474109181, 8547.10255423184, 5859.472647461749, 5898.852528686817, 5909.202257695156, 6067.726952287069, 6090.69546522681, 9782.559784809842, 10195.184385249311, 10520.842187367349, 10540.0, 10555.0, 10316.27786054969, 10320.690379288633, 10325.432101234504, 10338.172946164439, 10722.356658600509, 8084.63619137152, 8290.709825156331, 8353.624086915981, 8357.289630381485, 8368.916310836814, 8804.295656347605, 8806.867896981423, 8843.55782210874, 8866.0, 8875.023747387911, 8095.87856182146, 8104.675812967415, 8116.391270654843, 8119.512829230265, 8295.95852020736, 7895.7236496722335, 7901.328387086932, 7925.722599708983, 7935.603219838869, 8121.71573994931, 7085.50401471892, 7109.644517774194, 7136.107986501687, 7149.499535032498, 7162.140543134858, 4757.952420475889, 4957.182064959241, 5054.014467178865, 5090.363704536933, 5228.268042473981, 5143.768530416065, 5164.070467315919, 5186.688798672092, 5253.343332083489, 5276.472352764693], "value": 4646.275893977263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.84557952654376, 314.4497130022461, 318.50631521142253, 325.1237913822249, 327.6330509828992, 5178.844634660957, 5219.765110570024, 5342.370576974026, 5386.434424385442, 5395.973020134894, 5267.157254839227, 5324.680519168853, 5327.813526526573, 5347.598930080249, 5348.117560602505, 4181.351890456984, 4184.4141820145205, 4188.345603486621, 4190.9580904191025, 4202.558731333212, 3908.8045597720143, 3973.920521589581, 4154.797957082751, 4193.51774545928, 4195.559466256045, 4049.5343035550845, 4080.0, 4082.816273267695, 4128.442660240874, 4134.64855487284, 4102.79486025699, 4133.21468920904, 4149.771762553059, 4170.374443833449, 4171.353440216772, 3431.759776815624, 3679.632036796299, 3682.2451397463724, 3683.521142251517, 3687.6128006559193, 5165.199394093921, 5186.1442861927735, 5197.324347834795, 5201.817936372247, 5227.790888364499, 4663.062270769394, 4673.798448063088, 4680.157571637104, 4691.202495575761, 4692.0, 5453.23654688344, 5544.362398324204, 5559.722013899329, 5583.748731307101, 5599.496045355938, 3893.357595996657, 3930.272899513599, 3938.6061393860673, 3945.402112144586, 3966.4050392441363, 5795.739191736382, 5819.447152520546, 5825.116567299172, 5831.475167234927, 5847.3275573308765, 3929.724919255682, 4053.452783874139, 4077.653399461079, 4088.550259471456, 4095.4061661059577, 4116.958830411718, 4124.381342798604, 4130.648894843972, 4135.29699951003, 4145.958540414617, 4713.151632706145, 4729.69256998294, 4734.86363272812, 4748.0, 4751.3348131261655, 5526.143447765603, 5617.466340697627, 5634.098544232903, 5689.862027594415, 5694.8928896308635, 1410.4358256697199, 1412.8485284493106, 1421.8293804743496, 1425.6222101143205, 1432.1407155706702, 874.7419511244071, 882.7307671160229, 887.2281115429489, 891.8985053458973, 912.7535565397305, 4763.45220299663, 4802.871325238566, 4814.20565606678, 4814.990630855716, 4851.805927762921, 4993.076280888047, 5009.5491405773655, 5019.322391477177, 5044.445111037783, 5077.720725360066, 4768.427788665313, 4804.35141255926, 4809.44691360498, 4812.422509298906, 4970.2793095001025, 3054.701751755495, 3056.2359410147465, 3061.173483159579, 3070.8925187618775, 3071.7405863596264, 5879.588898664289, 5919.67441790697, 5921.911171332434, 5923.815236952627, 5928.288605367297, 5593.629560757559, 5874.471297583238, 5882.970585147047, 5896.734646940898, 5906.2912450505355, 5924.19312109802, 5935.347111817697, 5944.138099975479, 5962.40375962409, 5964.985952388104, 5554.805581804779, 5724.971375143097, 5764.83818568037, 5767.567432442667, 5774.845030993734, 5687.516561092353, 5711.657300561979, 5713.257276554022, 5718.428157184415, 5725.997950358754, 5927.9844852750275, 5933.249691340979, 5938.0, 5969.432903874299, 5980.252468441445, 3733.5519737631657, 3801.4107813288983, 4058.5535591084367, 4064.0695669556726, 4069.7761623111014, 5639.436056394327, 5669.489745922721, 5685.8862822742485, 5688.601797874275, 5708.743106560298, 5502.0, 5511.972440137773, 5542.448114527939, 5551.833444996657, 5559.944400555943, 5309.628326017296, 5417.485338892799, 5568.081266590967, 5646.011947909017, 5653.152027195953, 5453.7545810439415, 5464.3442786865835, 5471.480209380103, 5498.3127109111365, 5506.944930550643, 5366.558216011784, 5662.2639056922335, 5676.233708449472, 5685.744141513559, 5702.287214098238, 5481.260029895913, 5507.586930980113, 5512.889742205213, 5534.861628459321, 5558.0], "value": 4200.256349606907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.8268456912624, 767.6890859202024, 769.9730509432171, 792.5046845721424, 793.2226418110256, 15349.539513814589, 15909.44316948907, 16480.0, 16545.751813722803, 16570.171491425426, 13956.93021534891, 14002.509912153077, 14043.578692639237, 14075.859241407583, 14092.154470731737, 656.2387630348796, 774.8489044636298, 795.9840803183932, 807.9394045446616, 808.2644793238154, 790.9406794490408, 798.9720359787409, 799.0092285565886, 799.3645052183529, 800.7957970717459, 841.3017195727562, 847.2925107535185, 848.2154007543029, 848.3298194426395, 848.3340577646549, 687.9071325371061, 810.0320117459638, 837.7696133563319, 842.7134774176778, 843.9198276163754, 615.806021103351, 616.5221952986426, 616.7070641445277, 616.8334549671608, 617.009699432409, 15721.0, 15727.44953926607, 15743.134127622976, 15750.921245393814, 15807.972481788647, 14393.856061439355, 14832.555023349316, 14859.108453492718, 14877.18175500347, 15028.849711502964, 12837.614871553867, 12999.805002924964, 13143.27711975841, 13163.210207387492, 13353.732925341536, 4494.7078439901625, 4624.445066591997, 4708.858734237979, 4727.739974301412, 4730.976345118287, 73310.63344683296, 73834.63082684606, 74157.62921185413, 74572.62713686451, 74674.62662686706, 11689.473973671034, 11694.532218711156, 11721.494570513276, 11815.704607384712, 11942.582009629621, 22113.226037088625, 22293.55412891733, 22299.888500557394, 22318.0, 22684.092636294365, 8780.429272097294, 8793.472391656522, 8846.778830529289, 8953.920503316942, 9342.2526197904, 4880.194767863263, 4899.926501102415, 4942.8764280893265, 4949.7030178189425, 5089.720065396365, 3491.965080349215, 3605.134767655744, 3606.6573675500786, 3608.350496910529, 3611.2777444511203, 605.0923614578132, 615.0466776496413, 619.4703528483082, 626.5770604841714, 633.8573820890355, 572.4962033410573, 620.2525956222831, 669.4443611802193, 702.6346299924113, 713.653877869233, 9199.954000229956, 9460.148586627236, 9484.288678349152, 9515.33392662507, 9826.65606703762, 588.9734961926723, 639.8656282180795, 656.9128093006083, 665.6139439125295, 683.4771399879096, 4275.230458517434, 4341.283688191476, 4730.937896450765, 4749.501302363202, 5025.396952365666, 113522.0, 114772.42613787044, 115947.84052159371, 116031.41984290024, 116158.0, 89999.0, 92902.0709792907, 93121.0, 94358.5282073599, 94407.52796235975, 84174.57912710398, 88556.5572172135, 90991.0, 91228.5438572803, 91772.54113729254, 8855.0, 8884.644614215358, 8903.3322500814, 8983.907269303741, 8986.14631609996, 45385.54614453813, 46892.29661555011, 50978.74510627423, 51210.743946281524, 54227.72886135544, 70234.29765702278, 73660.0, 74091.25908740844, 74733.626331868, 77105.22894770981, 13094.410751516014, 13275.66810829737, 13294.40175192138, 13341.733165336833, 13782.93108534451, 14970.326335315154, 15141.848581514043, 15232.77150842716, 15302.693946120793, 15399.0, 14378.784318235026, 14726.337314820645, 14983.325750341479, 14992.925035374754, 15457.768133478232, 14728.0, 14922.104673719612, 15088.849111509184, 15215.847841521885, 15400.306986185424, 13471.865281347062, 14498.782518262024, 14575.44929637288, 14665.70668586601, 15046.849531504546, 11157.21899467062, 11318.36048834412, 11457.427128643369, 11617.6514704559, 11795.407619971536, 10371.117446464348, 10947.299097453923, 11129.443527823738, 11639.883601164218, 11683.532658693557], "value": 7638.6812141437185, "unit": "score"}], "name": "dromaeo_css", "value": 7228.240081521893, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 110.0, 110.0, 110.0, 110.0, 109.0, 108.0, 109.0, 109.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 133.0, 137.0, 187.0, 133.0, 150.0, 159.0, 127.0, 201.0, 132.0], "value": 152.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 161.0, 161.0, 161.0, 162.0, 160.0, 162.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 101.0, 75.0, 76.0, 73.0, 74.0, 113.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 107.0, 86.0, 85.0, 86.0, 116.0, 85.0, 86.0, 133.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0], "value": 145.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 53.0, 52.0, 51.0, 52.0, 54.0, 53.0, 52.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 80.0, 79.0, 81.0, 81.0, 81.0, 83.0, 112.0, 81.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 123.0, 125.0, 130.0, 128.0, 119.0, 120.0, 131.0, 137.0, 135.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 169.0, 160.0, 164.0, 159.0, 164.0, 163.0, 161.0, 157.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 67.0, 63.0, 63.0, 62.0, 61.0, 65.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1508.6, "alertThreshold": 2.0}]} 07:43:23 INFO - Return code: 0 07:43:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:43:23 INFO - # TBPL SUCCESS # 07:43:23 INFO - Running post-action listener: _resource_record_post_action 07:43:23 INFO - ##### 07:43:23 INFO - ##### Finished run-tests step (success) 07:43:23 INFO - ##### 07:43:23 INFO - Running post-run listener: _resource_record_post_run 07:43:23 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 2355200; Write bytes: 551342080; Read time: 1076; Write time: 460128 07:43:23 INFO - TinderboxPrint: CPU usage
13.2% 07:43:23 INFO - TinderboxPrint: I/O read bytes / time
2,355,200 / 1,076 07:43:23 INFO - TinderboxPrint: I/O write bytes / time
551,342,080 / 460,128 07:43:23 INFO - TinderboxPrint: CPU idle
8,274.4 (86.8%) 07:43:23 INFO - TinderboxPrint: CPU user
1,211.9 (12.7%) 07:43:23 INFO - TinderboxPrint: Swap in / out
0 / 0 07:43:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 262139904; Read time: 0; Write time: 251108 07:43:23 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 2355200; Write bytes: 288477184; Read time: 1076; Write time: 208976 07:43:24 INFO - Running post-run listener: _upload_blobber_files 07:43:24 INFO - Blob upload gear active. 07:43:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:43:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:43:24 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:43:24 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:43:24 INFO - (blobuploader) - INFO - Open directory for files ... 07:43:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:43:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:43:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:25 INFO - (blobuploader) - INFO - Done attempting. 07:43:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:43:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:29 INFO - (blobuploader) - INFO - Done attempting. 07:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:29 INFO - (blobuploader) - INFO - Done attempting. 07:43:29 INFO - (blobuploader) - INFO - Iteration through files over. 07:43:29 INFO - Return code: 0 07:43:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:43:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:43:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a5ea595b7bcafad324604354b3f08406da2dbb15d398cd597285249bd9e1ecc6733b8b1cbe1c25f98abb1982ba53f8df85c84a73e12158c846bfc77dc91abfd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e4423a2450d210e05fe0c3da5d394901d781635534c7fe1d9fe58765fc32f8521d7d57009dd28f9b105f6be3151fa4d84d116f495baaefbf2ad61ef74c83efc3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/751dfada22456d8248e95665e77c40258aa07c997fdf8ccd585acb02ea399c81dda0bb7673447479911209e9d1d1ea7f225229279141506aa4669435b937c2d4"} 07:43:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:43:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:43:29 INFO - Contents: 07:43:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a5ea595b7bcafad324604354b3f08406da2dbb15d398cd597285249bd9e1ecc6733b8b1cbe1c25f98abb1982ba53f8df85c84a73e12158c846bfc77dc91abfd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e4423a2450d210e05fe0c3da5d394901d781635534c7fe1d9fe58765fc32f8521d7d57009dd28f9b105f6be3151fa4d84d116f495baaefbf2ad61ef74c83efc3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/751dfada22456d8248e95665e77c40258aa07c997fdf8ccd585acb02ea399c81dda0bb7673447479911209e9d1d1ea7f225229279141506aa4669435b937c2d4"} 07:43:29 INFO - Running post-run listener: copy_logs_to_upload_dir 07:43:29 INFO - Copying logs to upload dir... 07:43:29 INFO - mkdir: /builds/slave/test/build/upload/logs 07:43:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.309157 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-06-17 07:43:29.130908) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 07:43:29.134909) ========= 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=4300f881736b422476174874000001bc-1466173371.528791-222818109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a5ea595b7bcafad324604354b3f08406da2dbb15d398cd597285249bd9e1ecc6733b8b1cbe1c25f98abb1982ba53f8df85c84a73e12158c846bfc77dc91abfd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e4423a2450d210e05fe0c3da5d394901d781635534c7fe1d9fe58765fc32f8521d7d57009dd28f9b105f6be3151fa4d84d116f495baaefbf2ad61ef74c83efc3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/751dfada22456d8248e95665e77c40258aa07c997fdf8ccd585acb02ea399c81dda0bb7673447479911209e9d1d1ea7f225229279141506aa4669435b937c2d4"} build_url:https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/ZlyPRGS1SCKHvxu8kLnbUg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a5ea595b7bcafad324604354b3f08406da2dbb15d398cd597285249bd9e1ecc6733b8b1cbe1c25f98abb1982ba53f8df85c84a73e12158c846bfc77dc91abfd3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e4423a2450d210e05fe0c3da5d394901d781635534c7fe1d9fe58765fc32f8521d7d57009dd28f9b105f6be3151fa4d84d116f495baaefbf2ad61ef74c83efc3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/751dfada22456d8248e95665e77c40258aa07c997fdf8ccd585acb02ea399c81dda0bb7673447479911209e9d1d1ea7f225229279141506aa4669435b937c2d4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 07:43:29.160381) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 07:43:29.160722) ========= 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=4300f881736b422476174874000001bc-1466173371.528791-222818109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 07:43:29.246565) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 07:43:29.246903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 07:43:29.247232) ========= ========= Total master_lag: 0.19 =========