builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1470094243.28 results: success (0) buildid: 20160801123750 builduid: 5a38664472d84844bb6ee371e0c4ddcd revision: 3998308ce6053f95dbb3bd814a72eaafb7b31fae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.276304) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.276736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.277021) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.291958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.292229) ========= 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=eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 16:30:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-08-01 16:30:43 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121010 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.426945) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.427273) ========= 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=eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020075 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:43.476721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 16:30:43.477111) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3998308ce6053f95dbb3bd814a72eaafb7b31fae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3998308ce6053f95dbb3bd814a72eaafb7b31fae --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=eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 16:30:43,560 truncating revision to first 12 chars 2016-08-01 16:30:43,560 Setting DEBUG logging. 2016-08-01 16:30:43,560 attempt 1/10 2016-08-01 16:30:43,560 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3998308ce605?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 16:30:44,704 unpacking tar archive at: mozilla-beta-3998308ce605/testing/mozharness/ program finished with exit code 0 elapsedTime=1.472827 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 16:30:44.963668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:44.963993) ========= script_repo_revision: 3998308ce6053f95dbb3bd814a72eaafb7b31fae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:44.964426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:44.964687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 16:30:44.973441) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-01 16:30:44.973730) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:30:45 INFO - MultiFileLogger online at 20160801 16:30:45 in /builds/slave/test 16:30:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:30:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:30:45 INFO - {'append_to_log': False, 16:30:45 INFO - 'base_work_dir': '/builds/slave/test', 16:30:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:30:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:30:45 INFO - 'branch': 'Mozilla-Beta', 16:30:45 INFO - 'buildbot_json_path': 'buildprops.json', 16:30:45 INFO - 'config_files': ('talos/linux_config.py',), 16:30:45 INFO - 'default_actions': ('clobber', 16:30:45 INFO - 'read-buildbot-config', 16:30:45 INFO - 'download-and-extract', 16:30:45 INFO - 'populate-webroot', 16:30:45 INFO - 'create-virtualenv', 16:30:45 INFO - 'install', 16:30:45 INFO - 'run-tests'), 16:30:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:30:45 INFO - 'download_minidump_stackwalk': True, 16:30:45 INFO - 'download_symbols': 'ondemand', 16:30:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:30:45 INFO - 'tooltool.py': '/tools/tooltool.py', 16:30:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:30:45 INFO - '/tools/misc-python/virtualenv.py')}, 16:30:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:30:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:30:45 INFO - 'installer_path': 'installer.exe', 16:30:45 INFO - 'log_level': 'info', 16:30:45 INFO - 'log_name': 'talos', 16:30:45 INFO - 'log_to_console': True, 16:30:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:30:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:30:45 INFO - 'opt_config_files': (), 16:30:45 INFO - 'pip_index': False, 16:30:45 INFO - 'sps_profile': False, 16:30:45 INFO - 'sps_profile_interval': 0, 16:30:45 INFO - 'suite': 'dromaeojs', 16:30:45 INFO - 'system_bits': '32', 16:30:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:30:45 INFO - 'title': 'talos-linux64-ix-010', 16:30:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:30:45 INFO - 'use_talos_json': True, 16:30:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:30:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:30:45 INFO - 'work_dir': 'build'} 16:30:45 INFO - ##### 16:30:45 INFO - ##### Running clobber step. 16:30:45 INFO - ##### 16:30:45 INFO - Running pre-action listener: _resource_record_pre_action 16:30:45 INFO - Running main action method: clobber 16:30:45 INFO - rmtree: /builds/slave/test/build 16:30:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:30:45 INFO - Running post-action listener: _resource_record_post_action 16:30:45 INFO - ##### 16:30:45 INFO - ##### Finished clobber step (success) 16:30:45 INFO - ##### 16:30:45 INFO - ##### 16:30:45 INFO - ##### Running read-buildbot-config step. 16:30:45 INFO - ##### 16:30:45 INFO - Running pre-action listener: _resource_record_pre_action 16:30:45 INFO - Running main action method: read_buildbot_config 16:30:45 INFO - Using buildbot properties: 16:30:45 INFO - { 16:30:45 INFO - "project": "", 16:30:45 INFO - "product": "firefox", 16:30:45 INFO - "script_repo_revision": "production", 16:30:45 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:30:45 INFO - "repository": "", 16:30:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:30:45 INFO - "buildid": "20160801123750", 16:30:45 INFO - "pgo_build": "True", 16:30:45 INFO - "basedir": "/builds/slave/test", 16:30:45 INFO - "buildnumber": 188, 16:30:45 INFO - "slavename": "talos-linux64-ix-010", 16:30:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:30:45 INFO - "platform": "ubuntu64_hw", 16:30:45 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:30:45 INFO - "branch": "mozilla-beta", 16:30:45 INFO - "repo_path": "releases/mozilla-beta", 16:30:45 INFO - "revision": "3998308ce6053f95dbb3bd814a72eaafb7b31fae", 16:30:45 INFO - "stage_platform": "linux64-pgo", 16:30:45 INFO - "builduid": "5a38664472d84844bb6ee371e0c4ddcd", 16:30:45 INFO - "slavebuilddir": "test" 16:30:45 INFO - } 16:30:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 16:30:45 INFO - Running post-action listener: _resource_record_post_action 16:30:45 INFO - ##### 16:30:45 INFO - ##### Finished read-buildbot-config step (success) 16:30:45 INFO - ##### 16:30:45 INFO - ##### 16:30:45 INFO - ##### Running download-and-extract step. 16:30:45 INFO - ##### 16:30:45 INFO - Running pre-action listener: _resource_record_pre_action 16:30:45 INFO - Running main action method: download_and_extract 16:30:45 INFO - mkdir: /builds/slave/test/build/tests 16:30: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')]} 16:30:45 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:30:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:30:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:30:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:30:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:30:46 INFO - Downloaded 1327 bytes. 16:30:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:30:46 INFO - Using the following test package requirements: 16:30:46 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 16:30:46 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:30:46 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 16:30:46 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'jsshell-linux-x86_64.zip'], 16:30:46 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:30:46 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 16:30:46 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 16:30:46 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 16:30:46 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:30:46 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:30:46 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:30:46 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:30:46 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:30:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:30:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:30:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:30:47 INFO - Downloaded 21967605 bytes. 16:30:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:30:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:30:48 INFO - Return code: 0 16:30:48 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:48 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:30:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:30:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:30:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:30:49 INFO - Downloaded 21967605 bytes. 16:30:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:30:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:30:50 INFO - Return code: 0 16:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:30:50 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:30:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 16:30:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 16:30:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 16:30:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:30:51 INFO - Downloaded 10833369 bytes. 16:30:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:30:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:30:51 INFO - Return code: 0 16:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:51 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:30:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:30:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:30:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:30:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:30:53 INFO - Downloaded 55283573 bytes. 16:30:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:30:53 INFO - mkdir: /builds/slave/test/properties 16:30:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:30:53 INFO - Writing to file /builds/slave/test/properties/build_url 16:30:53 INFO - Contents: 16:30:53 INFO - build_url:https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:30:53 INFO - Running post-action listener: _resource_record_post_action 16:30:53 INFO - Running post-action listener: set_extra_try_arguments 16:30:53 INFO - ##### 16:30:53 INFO - ##### Finished download-and-extract step (success) 16:30:53 INFO - ##### 16:30:53 INFO - ##### 16:30:53 INFO - ##### Running populate-webroot step. 16:30:53 INFO - ##### 16:30:53 INFO - Running pre-action listener: _resource_record_pre_action 16:30:53 INFO - Running main action method: populate_webroot 16:30:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:30:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:30:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:30:54 INFO - '--tppagecycles', 16:30:54 INFO - '7'], 16:30:54 INFO - 'tests': ['tsvgm']}}, 16:30:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:30:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:30:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:30:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:30:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['damp', 'tps']}, 16:30:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'tests': ['damp', 'tps']}, 16:30:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['dromaeo_dom']}, 16:30:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:30:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['a11yr', 16:30:54 INFO - 'ts_paint', 16:30:54 INFO - 'tpaint', 16:30:54 INFO - 'sessionrestore', 16:30:54 INFO - 'sessionrestore_no_auto_restore', 16:30:54 INFO - 'tabpaint']}, 16:30:54 INFO - 'other-e10s': {'tests': ['a11yr', 16:30:54 INFO - 'ts_paint', 16:30:54 INFO - 'tpaint', 16:30:54 INFO - 'sessionrestore', 16:30:54 INFO - 'sessionrestore_no_auto_restore', 16:30:54 INFO - 'tabpaint']}, 16:30:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:30:54 INFO - 'ts_paint', 16:30:54 INFO - 'tpaint', 16:30:54 INFO - 'sessionrestore', 16:30:54 INFO - 'sessionrestore_no_auto_restore', 16:30:54 INFO - 'tabpaint']}, 16:30:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:30:54 INFO - 'ts_paint', 16:30:54 INFO - 'tpaint', 16:30:54 INFO - 'sessionrestore', 16:30:54 INFO - 'sessionrestore_no_auto_restore', 16:30:54 INFO - 'tabpaint']}, 16:30:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['a11yr', 16:30:54 INFO - 'ts_paint', 16:30:54 INFO - 'tpaint', 16:30:54 INFO - 'sessionrestore', 16:30:54 INFO - 'sessionrestore_no_auto_restore', 16:30:54 INFO - 'tabpaint']}, 16:30:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['a11yr', 16:30:54 INFO - 'ts_paint', 16:30:54 INFO - 'tpaint', 16:30:54 INFO - 'sessionrestore', 16:30:54 INFO - 'sessionrestore_no_auto_restore', 16:30:54 INFO - 'tabpaint']}, 16:30:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['tsvgx', 16:30:54 INFO - 'tsvgr_opacity', 16:30:54 INFO - 'tart', 16:30:54 INFO - 'tscrollx', 16:30:54 INFO - 'cart']}, 16:30:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:30:54 INFO - 'tsvgr_opacity', 16:30:54 INFO - 'tart', 16:30:54 INFO - 'tscrollx', 16:30:54 INFO - 'cart']}, 16:30:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'talos_options': ['--disable-e10s'], 16:30:54 INFO - 'tests': ['tp5o']}, 16:30:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'tests': ['tp5o']}, 16:30:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'talos_options': ['--disable-e10s', 16:30:54 INFO - '--xperf_path', 16:30:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:54 INFO - 'tests': ['tp5n']}, 16:30:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:54 INFO - 'talos_options': ['--xperf_path', 16:30:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:54 INFO - 'tests': ['tp5n']}}, 16:30:54 INFO - 'talos.zip': {'path': '', 16:30:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:30:54 INFO - Running post-action listener: _resource_record_post_action 16:30:54 INFO - ##### 16:30:54 INFO - ##### Finished populate-webroot step (success) 16:30:54 INFO - ##### 16:30:54 INFO - ##### 16:30:54 INFO - ##### Running create-virtualenv step. 16:30:54 INFO - ##### 16:30:54 INFO - Running pre-action listener: _resource_record_pre_action 16:30:54 INFO - Running main action method: create_virtualenv 16:30:54 INFO - Creating virtualenv /builds/slave/test/build/venv 16:30:54 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 16:30:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:30:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:30:54 INFO - Using real prefix '/usr' 16:30:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:30:54 INFO - Installing distribute.............................................................................................................................................................................................done. 16:30:56 INFO - Installing pip.................done. 16:30:56 INFO - Return code: 0 16:30:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:30:56 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')]} 16:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:56 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')]} 16:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:56 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:30:56 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 16:30:56 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 16:30:56 INFO - Using env: {'DISPLAY': ':0', 16:30:56 INFO - 'HOME': '/home/cltbld', 16:30:56 INFO - 'LANG': 'en_US.UTF-8', 16:30:56 INFO - 'LANGUAGE': 'en_US:en', 16:30:56 INFO - 'LOGNAME': 'cltbld', 16:30:56 INFO - 'MAIL': '/var/mail/cltbld', 16:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:56 INFO - 'MOZ_NO_REMOTE': '1', 16:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:56 INFO - 'NO_EM_RESTART': '1', 16:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:56 INFO - 'PWD': '/builds/slave/test', 16:30:56 INFO - 'SHELL': '/bin/bash', 16:30:56 INFO - 'SHLVL': '1', 16:30:56 INFO - 'TERM': 'linux', 16:30:56 INFO - 'TMOUT': '86400', 16:30:56 INFO - 'USER': 'cltbld', 16:30:56 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:56 INFO - '_': '/tools/buildbot/bin/python'} 16:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:56 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 16:30:56 INFO - Cleaning up... 16:30:56 INFO - Return code: 0 16:30:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:30:56 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')]} 16:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:56 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')]} 16:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:56 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:30:56 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 16:30:56 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 16:30:56 INFO - Using env: {'DISPLAY': ':0', 16:30:56 INFO - 'HOME': '/home/cltbld', 16:30:56 INFO - 'LANG': 'en_US.UTF-8', 16:30:56 INFO - 'LANGUAGE': 'en_US:en', 16:30:56 INFO - 'LOGNAME': 'cltbld', 16:30:56 INFO - 'MAIL': '/var/mail/cltbld', 16:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:56 INFO - 'MOZ_NO_REMOTE': '1', 16:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:56 INFO - 'NO_EM_RESTART': '1', 16:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:56 INFO - 'PWD': '/builds/slave/test', 16:30:56 INFO - 'SHELL': '/bin/bash', 16:30:56 INFO - 'SHLVL': '1', 16:30:56 INFO - 'TERM': 'linux', 16:30:56 INFO - 'TMOUT': '86400', 16:30:56 INFO - 'USER': 'cltbld', 16:30:56 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:56 INFO - '_': '/tools/buildbot/bin/python'} 16:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:57 INFO - Downloading/unpacking psutil>=3.1.1 16:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:30:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:59 INFO - Installing collected packages: psutil 16:30:59 INFO - Running setup.py install for psutil 16:30:59 INFO - building 'psutil._psutil_linux' extension 16:30:59 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 16:30:59 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 16:30:59 INFO - building 'psutil._psutil_posix' extension 16:30:59 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 16:30:59 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 16:30:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:59 INFO - Successfully installed psutil 16:30:59 INFO - Cleaning up... 16:30:59 INFO - Return code: 0 16:30:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:30:59 INFO - Using env: {'DISPLAY': ':0', 16:30:59 INFO - 'HOME': '/home/cltbld', 16:30:59 INFO - 'LANG': 'en_US.UTF-8', 16:30:59 INFO - 'LANGUAGE': 'en_US:en', 16:30:59 INFO - 'LOGNAME': 'cltbld', 16:30:59 INFO - 'MAIL': '/var/mail/cltbld', 16:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:59 INFO - 'MOZ_NO_REMOTE': '1', 16:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:59 INFO - 'NO_EM_RESTART': '1', 16:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:59 INFO - 'PWD': '/builds/slave/test', 16:30:59 INFO - 'SHELL': '/bin/bash', 16:30:59 INFO - 'SHLVL': '1', 16:30:59 INFO - 'TERM': 'linux', 16:30:59 INFO - 'TMOUT': '86400', 16:30:59 INFO - 'USER': 'cltbld', 16:30:59 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:59 INFO - '_': '/tools/buildbot/bin/python'} 16:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:31:01 INFO - Installing collected packages: mozsystemmonitor 16:31:01 INFO - Running setup.py install for mozsystemmonitor 16:31:01 INFO - Successfully installed mozsystemmonitor 16:31:01 INFO - Cleaning up... 16:31:01 INFO - Return code: 0 16:31:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:01 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:01 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 16:31:01 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 16:31:01 INFO - Using env: {'DISPLAY': ':0', 16:31:01 INFO - 'HOME': '/home/cltbld', 16:31:01 INFO - 'LANG': 'en_US.UTF-8', 16:31:01 INFO - 'LANGUAGE': 'en_US:en', 16:31:01 INFO - 'LOGNAME': 'cltbld', 16:31:01 INFO - 'MAIL': '/var/mail/cltbld', 16:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:01 INFO - 'MOZ_NO_REMOTE': '1', 16:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:01 INFO - 'NO_EM_RESTART': '1', 16:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:01 INFO - 'PWD': '/builds/slave/test', 16:31:01 INFO - 'SHELL': '/bin/bash', 16:31:01 INFO - 'SHLVL': '1', 16:31:01 INFO - 'TERM': 'linux', 16:31:01 INFO - 'TMOUT': '86400', 16:31:01 INFO - 'USER': 'cltbld', 16:31:01 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:01 INFO - '_': '/tools/buildbot/bin/python'} 16:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:02 INFO - Downloading/unpacking jsonschema==2.5.1 16:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:31:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 INFO - Downloading functools32-3.2.3-2.tar.gz 16:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:31:04 INFO - warning: no files found matching '*.txt' 16:31:04 INFO - no previously-included directories found matching 'build' 16:31:04 INFO - no previously-included directories found matching 'dist' 16:31:04 INFO - no previously-included directories found matching '.git*' 16:31:04 INFO - Installing collected packages: jsonschema, functools32 16:31:04 INFO - Running setup.py install for functools32 16:31:04 INFO - warning: no files found matching '*.txt' 16:31:04 INFO - no previously-included directories found matching 'build' 16:31:04 INFO - no previously-included directories found matching 'dist' 16:31:04 INFO - no previously-included directories found matching '.git*' 16:31:04 INFO - Successfully installed jsonschema functools32 16:31:04 INFO - Cleaning up... 16:31:04 INFO - Return code: 0 16:31:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:31:04 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')]} 16:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:04 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')]} 16:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:04 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:04 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 16:31:04 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 16:31:04 INFO - Using env: {'DISPLAY': ':0', 16:31:04 INFO - 'HOME': '/home/cltbld', 16:31:04 INFO - 'LANG': 'en_US.UTF-8', 16:31:04 INFO - 'LANGUAGE': 'en_US:en', 16:31:04 INFO - 'LOGNAME': 'cltbld', 16:31:04 INFO - 'MAIL': '/var/mail/cltbld', 16:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:04 INFO - 'MOZ_NO_REMOTE': '1', 16:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:04 INFO - 'NO_EM_RESTART': '1', 16:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:04 INFO - 'PWD': '/builds/slave/test', 16:31:04 INFO - 'SHELL': '/bin/bash', 16:31:04 INFO - 'SHLVL': '1', 16:31:04 INFO - 'TERM': 'linux', 16:31:04 INFO - 'TMOUT': '86400', 16:31:04 INFO - 'USER': 'cltbld', 16:31:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:04 INFO - '_': '/tools/buildbot/bin/python'} 16:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:31:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:31:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:07 INFO - Downloading docopt-0.6.1.tar.gz 16:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:31:07 INFO - Installing collected packages: blobuploader, requests, docopt 16:31:07 INFO - Running setup.py install for blobuploader 16:31:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:31:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:31:07 INFO - Running setup.py install for requests 16:31:07 INFO - Running setup.py install for docopt 16:31:07 INFO - Successfully installed blobuploader requests docopt 16:31:07 INFO - Cleaning up... 16:31:07 INFO - Return code: 0 16:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:07 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')]} 16:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:07 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')]} 16:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:07 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:07 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 16:31:07 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 16:31:07 INFO - Using env: {'DISPLAY': ':0', 16:31:07 INFO - 'HOME': '/home/cltbld', 16:31:07 INFO - 'LANG': 'en_US.UTF-8', 16:31:07 INFO - 'LANGUAGE': 'en_US:en', 16:31:07 INFO - 'LOGNAME': 'cltbld', 16:31:07 INFO - 'MAIL': '/var/mail/cltbld', 16:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:07 INFO - 'MOZ_NO_REMOTE': '1', 16:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:07 INFO - 'NO_EM_RESTART': '1', 16:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:07 INFO - 'PWD': '/builds/slave/test', 16:31:07 INFO - 'SHELL': '/bin/bash', 16:31:07 INFO - 'SHLVL': '1', 16:31:07 INFO - 'TERM': 'linux', 16:31:07 INFO - 'TMOUT': '86400', 16:31:07 INFO - 'USER': 'cltbld', 16:31:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:07 INFO - '_': '/tools/buildbot/bin/python'} 16:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:08 INFO - Running setup.py (path:/tmp/pip-0b_y4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:08 INFO - Running setup.py (path:/tmp/pip-CgMY33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:08 INFO - Running setup.py (path:/tmp/pip-P7aSJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:08 INFO - Running setup.py (path:/tmp/pip-W4JwKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:08 INFO - Running setup.py (path:/tmp/pip-OyuPkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:08 INFO - Running setup.py (path:/tmp/pip-0Kayij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:08 INFO - Running setup.py (path:/tmp/pip-ij4BAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:08 INFO - Running setup.py (path:/tmp/pip-ChUP2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:08 INFO - Running setup.py (path:/tmp/pip-qPNW1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:08 INFO - Running setup.py (path:/tmp/pip-irEYs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:08 INFO - Running setup.py (path:/tmp/pip-PlyN5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:08 INFO - Running setup.py (path:/tmp/pip-XVrvJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:09 INFO - Running setup.py (path:/tmp/pip-QdWW32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:09 INFO - Running setup.py (path:/tmp/pip-_lnznb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:09 INFO - Running setup.py (path:/tmp/pip-PlD6J2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:09 INFO - Running setup.py (path:/tmp/pip-zjhBDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:09 INFO - Running setup.py (path:/tmp/pip-HIbSIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:31:09 INFO - Running setup.py install for manifestparser 16:31:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:31:09 INFO - Running setup.py install for mozcrash 16:31:09 INFO - Running setup.py install for mozdebug 16:31:09 INFO - Running setup.py install for mozdevice 16:31:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:31:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:31:09 INFO - Running setup.py install for mozfile 16:31:09 INFO - Running setup.py install for mozhttpd 16:31:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for mozinfo 16:31:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for mozInstall 16:31:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for mozleak 16:31:10 INFO - Running setup.py install for mozlog 16:31:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for moznetwork 16:31:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for mozprocess 16:31:10 INFO - Running setup.py install for mozprofile 16:31:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for mozrunner 16:31:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:31:10 INFO - Running setup.py install for mozscreenshot 16:31:10 INFO - Running setup.py install for moztest 16:31:11 INFO - Running setup.py install for mozversion 16:31:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:31:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:31:11 INFO - Cleaning up... 16:31:11 INFO - Return code: 0 16:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:11 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:11 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 16:31:11 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 16:31:11 INFO - Using env: {'DISPLAY': ':0', 16:31:11 INFO - 'HOME': '/home/cltbld', 16:31:11 INFO - 'LANG': 'en_US.UTF-8', 16:31:11 INFO - 'LANGUAGE': 'en_US:en', 16:31:11 INFO - 'LOGNAME': 'cltbld', 16:31:11 INFO - 'MAIL': '/var/mail/cltbld', 16:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:11 INFO - 'MOZ_NO_REMOTE': '1', 16:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:11 INFO - 'NO_EM_RESTART': '1', 16:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:11 INFO - 'PWD': '/builds/slave/test', 16:31:11 INFO - 'SHELL': '/bin/bash', 16:31:11 INFO - 'SHLVL': '1', 16:31:11 INFO - 'TERM': 'linux', 16:31:11 INFO - 'TMOUT': '86400', 16:31:11 INFO - 'USER': 'cltbld', 16:31:11 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:11 INFO - '_': '/tools/buildbot/bin/python'} 16:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:11 INFO - Running setup.py (path:/tmp/pip-hN6zEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:11 INFO - Running setup.py (path:/tmp/pip-BdSaKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:11 INFO - Running setup.py (path:/tmp/pip-OHLa1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:11 INFO - Running setup.py (path:/tmp/pip-LLtVkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:11 INFO - Running setup.py (path:/tmp/pip-szvsPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:11 INFO - Running setup.py (path:/tmp/pip-TRuJP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:11 INFO - Running setup.py (path:/tmp/pip-OBg1YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:11 INFO - Running setup.py (path:/tmp/pip-MGiE_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:11 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)) 16:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:11 INFO - Running setup.py (path:/tmp/pip-Gtd5IW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:12 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)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:12 INFO - Running setup.py (path:/tmp/pip-DURWN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:12 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)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:12 INFO - Running setup.py (path:/tmp/pip-q11lSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:12 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)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:12 INFO - Running setup.py (path:/tmp/pip-dUl_Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:12 INFO - Running setup.py (path:/tmp/pip-6gCze5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:12 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)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:12 INFO - Running setup.py (path:/tmp/pip-AG_dG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:12 INFO - Running setup.py (path:/tmp/pip-U2k7V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:12 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)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:12 INFO - Running setup.py (path:/tmp/pip-987snI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:12 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)) 16:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:12 INFO - Running setup.py (path:/tmp/pip-SajLv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:12 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)) 16:31:12 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)) 16:31:12 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)) 16:31:12 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)) 16:31:12 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)) 16:31:12 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)) 16:31:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:14 INFO - Downloading blessings-1.6.tar.gz 16:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:31:14 INFO - Installing collected packages: blessings 16:31:14 INFO - Running setup.py install for blessings 16:31:14 INFO - Successfully installed blessings 16:31:14 INFO - Cleaning up... 16:31:14 INFO - Return code: 0 16:31:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:14 INFO - Reading from file tmpfile_stdout 16:31:14 INFO - Current package versions: 16:31:14 INFO - argparse == 1.2.1 16:31:14 INFO - blessings == 1.6 16:31:14 INFO - blobuploader == 1.2.4 16:31:14 INFO - docopt == 0.6.1 16:31:14 INFO - functools32 == 3.2.3-2 16:31:14 INFO - jsonschema == 2.5.1 16:31:14 INFO - manifestparser == 1.1 16:31:14 INFO - mozInstall == 1.12 16:31:14 INFO - mozcrash == 0.17 16:31:14 INFO - mozdebug == 0.1 16:31:14 INFO - mozdevice == 0.48 16:31:14 INFO - mozfile == 1.2 16:31:14 INFO - mozhttpd == 0.7 16:31:14 INFO - mozinfo == 0.9 16:31:14 INFO - mozleak == 0.1 16:31:14 INFO - mozlog == 3.2 16:31:14 INFO - moznetwork == 0.27 16:31:14 INFO - mozprocess == 0.23 16:31:14 INFO - mozprofile == 0.28 16:31:14 INFO - mozrunner == 6.12 16:31:14 INFO - mozscreenshot == 0.1 16:31:14 INFO - mozsystemmonitor == 0.3 16:31:14 INFO - moztest == 0.7 16:31:14 INFO - mozversion == 1.4 16:31:14 INFO - psutil == 3.1.1 16:31:14 INFO - requests == 1.2.3 16:31:14 INFO - wsgiref == 0.1.2 16:31:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:14 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:14 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 16:31:14 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 16:31:14 INFO - Using env: {'DISPLAY': ':0', 16:31:14 INFO - 'HOME': '/home/cltbld', 16:31:14 INFO - 'LANG': 'en_US.UTF-8', 16:31:14 INFO - 'LANGUAGE': 'en_US:en', 16:31:14 INFO - 'LOGNAME': 'cltbld', 16:31:14 INFO - 'MAIL': '/var/mail/cltbld', 16:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:14 INFO - 'MOZ_NO_REMOTE': '1', 16:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:14 INFO - 'NO_EM_RESTART': '1', 16:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:14 INFO - 'PWD': '/builds/slave/test', 16:31:14 INFO - 'SHELL': '/bin/bash', 16:31:14 INFO - 'SHLVL': '1', 16:31:14 INFO - 'TERM': 'linux', 16:31:14 INFO - 'TMOUT': '86400', 16:31:14 INFO - 'USER': 'cltbld', 16:31:14 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:14 INFO - '_': '/tools/buildbot/bin/python'} 16:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 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)) 16:31:15 INFO - Cleaning up... 16:31:15 INFO - Return code: 0 16:31:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:31:15 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')]} 16:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:15 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')]} 16:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:15 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:15 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 16:31:15 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 16:31:15 INFO - Using env: {'DISPLAY': ':0', 16:31:15 INFO - 'HOME': '/home/cltbld', 16:31:15 INFO - 'LANG': 'en_US.UTF-8', 16:31:15 INFO - 'LANGUAGE': 'en_US:en', 16:31:15 INFO - 'LOGNAME': 'cltbld', 16:31:15 INFO - 'MAIL': '/var/mail/cltbld', 16:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:15 INFO - 'MOZ_NO_REMOTE': '1', 16:31:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:15 INFO - 'NO_EM_RESTART': '1', 16:31:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:15 INFO - 'PWD': '/builds/slave/test', 16:31:15 INFO - 'SHELL': '/bin/bash', 16:31:15 INFO - 'SHLVL': '1', 16:31:15 INFO - 'TERM': 'linux', 16:31:15 INFO - 'TMOUT': '86400', 16:31:15 INFO - 'USER': 'cltbld', 16:31:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:15 INFO - '_': '/tools/buildbot/bin/python'} 16:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:31:15 INFO - Cleaning up... 16:31:15 INFO - Return code: 0 16:31:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:31:15 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')]} 16:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:15 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')]} 16:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:15 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 0x7f2280d17f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1414020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', '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 16:31:15 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 16:31:15 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 16:31:15 INFO - Using env: {'DISPLAY': ':0', 16:31:15 INFO - 'HOME': '/home/cltbld', 16:31:15 INFO - 'LANG': 'en_US.UTF-8', 16:31:15 INFO - 'LANGUAGE': 'en_US:en', 16:31:15 INFO - 'LOGNAME': 'cltbld', 16:31:15 INFO - 'MAIL': '/var/mail/cltbld', 16:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:15 INFO - 'MOZ_NO_REMOTE': '1', 16:31:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:15 INFO - 'NO_EM_RESTART': '1', 16:31:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:15 INFO - 'PWD': '/builds/slave/test', 16:31:15 INFO - 'SHELL': '/bin/bash', 16:31:15 INFO - 'SHLVL': '1', 16:31:15 INFO - 'TERM': 'linux', 16:31:15 INFO - 'TMOUT': '86400', 16:31:15 INFO - 'USER': 'cltbld', 16:31:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:15 INFO - '_': '/tools/buildbot/bin/python'} 16:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:15 INFO - Downloading/unpacking flake8 16:31:15 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 16:31:15 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 16:31:15 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 16:31:15 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 16:31:15 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 16:31:15 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 16:31:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:31:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:31:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:31:18 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 16:31:18 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 16:31:18 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 16:31:18 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 16:31:18 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 16:31:18 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 16:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:31:18 INFO - no previously-included directories found matching 'docs/_build' 16:31:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:31:18 INFO - Running setup.py install for pep8 16:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:31:18 INFO - no previously-included directories found matching 'docs/_build' 16:31:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:31:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:31:18 INFO - Cleaning up... 16:31:18 INFO - Return code: 0 16:31:18 INFO - Running post-action listener: _resource_record_post_action 16:31:18 INFO - Running post-action listener: _start_resource_monitoring 16:31:18 INFO - Starting resource monitoring. 16:31:18 INFO - ##### 16:31:18 INFO - ##### Finished create-virtualenv step (success) 16:31:18 INFO - ##### 16:31:18 INFO - ##### 16:31:18 INFO - ##### Running install step. 16:31:18 INFO - ##### 16:31:18 INFO - Running pre-action listener: _resource_record_pre_action 16:31:18 INFO - Running main action method: install 16:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:18 INFO - Reading from file tmpfile_stdout 16:31:18 INFO - Detecting whether we're running mozinstall >=1.0... 16:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:31:18 INFO - Reading from file tmpfile_stdout 16:31:18 INFO - Output received: 16:31:18 INFO - Usage: mozinstall [options] installer 16:31:18 INFO - Options: 16:31:18 INFO - -h, --help show this help message and exit 16:31:18 INFO - -d DEST, --destination=DEST 16:31:18 INFO - Directory to install application into. [default: 16:31:18 INFO - "/builds/slave/test"] 16:31:18 INFO - --app=APP Application being installed. [default: firefox] 16:31:18 INFO - mkdir: /builds/slave/test/build/application 16:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:31:32 INFO - Reading from file tmpfile_stdout 16:31:32 INFO - Output received: 16:31:32 INFO - /builds/slave/test/build/application/firefox/firefox 16:31:32 INFO - Running post-action listener: _resource_record_post_action 16:31:32 INFO - ##### 16:31:32 INFO - ##### Finished install step (success) 16:31:32 INFO - ##### 16:31:32 INFO - ##### 16:31:32 INFO - ##### Running run-tests step. 16:31:32 INFO - ##### 16:31:32 INFO - Running pre-action listener: _resource_record_pre_action 16:31:32 INFO - Running main action method: run_tests 16:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:31:32 INFO - Python 2.7.3 16:31:32 INFO - Return code: 0 16:31:32 INFO - grabbing minidump binary from tooltool 16:31:32 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')]} 16:31:32 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 0x14a62e0>, '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 0x1412140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14125d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:31:32 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 16:31:32 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 16:31:32 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 16:31:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:31:32 INFO - Return code: 0 16:31:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:31:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:31:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:31:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:31:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:31:33 INFO - Return code: 0 16:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 16:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 16:31:33 INFO - Using env: {'DISPLAY': ':0', 16:31:33 INFO - 'HOME': '/home/cltbld', 16:31:33 INFO - 'LANG': 'en_US.UTF-8', 16:31:33 INFO - 'LANGUAGE': 'en_US:en', 16:31:33 INFO - 'LOGNAME': 'cltbld', 16:31:33 INFO - 'MAIL': '/var/mail/cltbld', 16:31:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:31:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:33 INFO - 'MOZ_NO_REMOTE': '1', 16:31:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:33 INFO - 'NO_EM_RESTART': '1', 16:31:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:33 INFO - 'PWD': '/builds/slave/test', 16:31:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:31:33 INFO - 'SHELL': '/bin/bash', 16:31:33 INFO - 'SHLVL': '1', 16:31:33 INFO - 'TERM': 'linux', 16:31:33 INFO - 'TMOUT': '86400', 16:31:33 INFO - 'USER': 'cltbld', 16:31:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338', 16:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:33 INFO - '_': '/tools/buildbot/bin/python'} 16:31:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 16:31:33 INFO - mozversion application_buildid: 20160801123750 16:31:33 INFO - mozversion application_changeset: 3998308ce6053f95dbb3bd814a72eaafb7b31fae 16:31:33 INFO - mozversion application_display_name: Firefox 16:31:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:31:33 INFO - mozversion application_name: Firefox 16:31:33 INFO - mozversion application_remotingname: firefox 16:31:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:31:33 INFO - mozversion application_vendor: Mozilla 16:31:33 INFO - mozversion application_version: 49.0 16:31:33 INFO - mozversion platform_buildid: 20160801123750 16:31:33 INFO - mozversion platform_changeset: 3998308ce6053f95dbb3bd814a72eaafb7b31fae 16:31:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:31:33 INFO - mozversion platform_version: 49.0 16:31:33 INFO - using testdate: 1470094293 16:31:33 INFO - actual date: 1470094293 16:31:33 INFO - starting webserver on 'localhost:36177' 16:31:33 INFO - SUITE-START | Running 2 tests 16:31:33 INFO - TEST-START | dromaeo_css 16:31:33 INFO - operating with platform_type : linux_ 16:31:33 INFO - Initialising browser for dromaeo_css test... 16:31:33 INFO - TEST-INFO | started process 12609 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4T_ZSa/profile http://localhost:36177/getInfo.html) 16:31:37 INFO - PROCESS | 12609 | __metrics Screen width/height:1600/1200 16:31:37 INFO - PROCESS | 12609 | colorDepth:24 16:31:37 INFO - PROCESS | 12609 | Browser inner width/height: 1024/697 16:31:37 INFO - PROCESS | 12609 | __metrics 16:31:38 INFO - PROCESS | 12609 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:31:38 ERROR - PROCESS | 12609 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:31:39 INFO - TEST-INFO | 12609: exit 0 16:31:39 INFO - Browser initialized. 16:31:39 INFO - Running cycle 1/1 for dromaeo_css test... 16:31:39 INFO - TEST-INFO | started process 12699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4T_ZSa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:51 INFO - PROCESS | 12699 | RSS: Main: 141303808 16:31:51 INFO - PROCESS | 12699 | 16:32:42 INFO - PROCESS | 12699 | 1470094362002 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] 16:34:50 INFO - PROCESS | 12699 | Cycle 1(1): loaded http://localhost:36177/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36177/tests/dromaeo/cssquery-ext.html) 16:34:51 INFO - PROCESS | 12699 | RSS: Main: 272629760 16:34:51 INFO - PROCESS | 12699 | 16:37:53 INFO - PROCESS | 12699 | Cycle 1(1): loaded http://localhost:36177/tests/dromaeo/cssquery-ext.html (next: http://localhost:36177/tests/dromaeo/cssquery-jquery.html) 16:37:54 INFO - PROCESS | 12699 | RSS: Main: 216743936 16:37:54 INFO - PROCESS | 12699 | 16:40:56 INFO - PROCESS | 12699 | Cycle 1(1): loaded http://localhost:36177/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36177/tests/dromaeo/cssquery-mootools.html) 16:40:56 INFO - PROCESS | 12699 | RSS: Main: 224591872 16:40:56 INFO - PROCESS | 12699 | 16:43:58 INFO - PROCESS | 12699 | Cycle 1(1): loaded http://localhost:36177/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36177/tests/dromaeo/cssquery-prototype.html) 16:43:59 INFO - PROCESS | 12699 | RSS: Main: 220504064 16:43:59 INFO - PROCESS | 12699 | 16:47:01 INFO - PROCESS | 12699 | Cycle 1(1): loaded http://localhost:36177/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36177/tests/dromaeo/cssquery-yui.html) 16:47:02 INFO - PROCESS | 12699 | RSS: Main: 215633920 16:47:02 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | Cycle 1(1): loaded http://localhost:36177/tests/dromaeo/cssquery-yui.html (next: http://localhost:36177/tests/dromaeo/cssquery-dojo.html) 16:50:04 INFO - PROCESS | 12699 | RSS: Main: 258002944 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | __start_tp_report 16:50:04 INFO - PROCESS | 12699 | _x_x_mozilla_page_load 16:50:04 INFO - PROCESS | 12699 | _x_x_mozilla_page_load_details 16:50:04 INFO - PROCESS | 12699 | |i|pagename|runs| 16:50:04 INFO - PROCESS | 12699 | |0;dojo.html;3317.971428857054;3332.70005699487;3340.7327413806897;3343.2644818140006;3367.4275373186556;5380.946190538099;5447.727613619313;5469.453054694529;5480.808171713991;5512.173174023898;5371.75827087781;5475.397706252309;5494.09347457669;5497.505224529791;5536.141898005805;4830.9275360869615;4852.320675105488;4901.044296362202;4929.408470983487;4968.254761785743;4873.683210591318;4894.290327902452;4929.728864912428;4943.604511639078;4963.156263435207;4900.705957642535;4906.9263961040615;4933.950660493403;4978.626603004771;4979.526944940225;4964.801407943678;4990.550850423461;4991.550760431579;4991.7753701083175;5016.3227964224725;4622.14490319292;4784.952150478485;4892.9510704892855;4920.507949205086;4923.236898280772;5459.972700136514;5469.480399362054;5488.670679759227;5494.450554944514;5508.64193827404;5938.317093534212;5973.402659733992;5985.34161242263;5992.43071908168;5992.940070599282;5673.432656734335;5759.5968282220265;5761.942380576224;5954.4938680212235;6032.306284777263;5518.668879867221;5522.889542209134;5523.889522209573;5527.143292789624;5533.1976863354585;6017.247844019497;6023;6026.5178785697035;6029.879402411927;6038.486728628116;5629.268195134607;5681.460261275172;5725.77096916127;5739.942600573941;5779.306483221956;5871.55963302754;5872.412758724179;5909;5914.733836977346;5920.348761636216;5776.1624564437925;5805.390434004453;5816.302043754692;5821.970890145522;5833.066709326487;5636.520895723827;5715.799947001835;5790.768369265183;5795;5833.358330583633;4555.749433781108;4564.748938808331;4567.725936443824;4575.656825738083;4591.632669386441;4016.7188296819527;4085.6935729820384;4103.692223083281;4124.13393187428;4129.876103716893;5543.362513311021;5565.30433695788;5607.43925607436;5613.522850557667;5641.407652196543;5883.117532370179;5906.911396329058;5907.409259074058;5910.615809972338;5945.256842894638;5484.670919744828;5491.341039075257;5538.529225015838;5579.079451890392;5653.491185793298;5048.3184770056305;5065.037642847848;5071.340725705634;5074.898502030012;5082.440931497532;6050.576459647782;6051.183090282843;6061.030235162353;6062.242219722535;6068.787592434332;5686.857828554319;5789.169933729964;5804.071348584206;5839.182514448065;5887.558433117448;6114.905143643943;6139.522803442411;6141.539384546266;6143.201383820075;6168.568200225941;5787.681677507778;5792.420757924174;5793;5799.971000144972;5818.359980402068;5691.260136182435;5721.828345149652;5735.3977832327;5752.798652047159;5881.882362352814;5641.464060914371;5676.460736230051;5679.801206957736;5690.231818704422;5690.317161940594;5603.663780173202;5661.46216109469;5667.234923285304;5673.886522269614;5674.4892959633835;5543.445655434424;5555.055640541199;5618.073017952155;5648.689322087324;5698.1452782083015;5817.301923769006;5824.5340372770925;5849.766009359578;5859.882802344015;5873.853153671193;5509.834704958857;5702.258706368146;5709.457601527848;5718.828435146952;5740.942590574041 16:50:04 INFO - PROCESS | 12699 | |1;ext.html;601.7833579911234;642.6304874697049;653.4380432827767;658.720043981308;666.8399584099811;32998.50502242468;45279.547204527946;45926;45982.31026534601;46040.76979615097;48256.27615585769;48967.51032489674;48989;49013;49041;8490.617922193516;9154.81690366194;9286;10747.570097196103;11006.119510439186;11009;11104.888951110466;11114.722131946726;11117.888821111806;11125.666230013112;11165.83251251232;11212.271202371861;11213.10295176388;11216.607418740346;11223.494942727597;11047.447627618627;11082.778344433107;11095.334279943148;11101.72245693856;11105.056070234039;11057.225994180411;11087.50106245221;11116.61091861781;11117.610883619116;11125.109991200685;44141.77929110366;44247;44455.55544444547;44552.10895782099;44578.77710611458;3570.250247448039;3617.5478065241846;4250.298700714378;4276.700630955835;4362.345648152771;34070.48894266588;34395;34456.31087378263;34470.48294275588;34552.82723586391;4678.345031695565;4686.742229177394;4765.975315307225;4766.142094423002;4846.806127754886;361770;362303;362595.1870240658;363021;364387;37840.62159378426;63047;63302.68348658319;63619.36380636227;63651.68174159192;37600.81199593985;39356.606433935296;39370.803145984086;39371;39424.21151576952;13981.300934953211;14169.716605667829;14172.149671019772;14173;14418.855811441961;11393.886061139283;11556.133290003283;11617.302961822317;11791.233569817934;11805.586804461802;9215;9254.12085851843;9261.536923153815;9318.813623727623;9324;3532.293541291752;3593.4789455528803;3605.5853576839;3615.078155070493;3668.9633103669157;1075.2419544221304;1093.8031154392127;1102.5589764094266;1125.4654039331251;1198.9160758746978;34325.828370857984;34394;34506;34591.65408345935;34664.82667586646;1187.5299377295205;1208.0396085112357;1252.1360261419773;1267.5690265309838;1287.2533930320392;17788.199531021135;17931.73102403465;18082;18151.364702235358;18162.81837181638;557034;558074;559232;560979;562294;63871.6806415965;64396;64415.355846441875;64427.67786161039;64468;1355.830521184852;1403.1861520318396;1445.8626430489296;1459.9416023359158;1484.85893840085;30927.84536077305;33647.32705345928;33807.66192338045;33986.32027359489;34643.307133857685;53324.73337633287;53801.192982106084;54045;54055.459445405046;54143.458565415414;415454;416228;416278;418059;419173;26766.866165669046;30127.69872301249;30651.386972260876;30767.84616076995;30982;51681.7415912918;57441.42558574361;57964.420355795904;58267.70866145642;58805.41194587999;44165.558344417426;44374;44710;45330.76646849854;45865.770671146434;43954.780226098665;44341.556584435035;44540.77729611331;44682;45801.77099114483;44023.339649905916;44467.777661112785;44980;45092.54907450883;45436.318455222536;37099.62900370962;39147;40096.198076037734;40124.598754013256;40342.39486407647;25739.485210296065;25892.352691182117;26047.479050418508;26119.608205877306;26371 16:50:04 INFO - PROCESS | 12699 | |2;jquery.html;424.69634211538744;427.4037717384251;427.8352834158849;428.5757100470535;443.22435737459443;18313.084345782725;19082.618347633055;19660.311889083885;19694.113764880578;20040.599188016247;18282.451526454195;18416.355427560036;18962.715559266588;19035.428937131874;19114.23543058276;5683.886322273563;5871.031279838821;5903.911441328383;5909.349971503131;5915.290165180184;4297.699161058727;5214.035403450355;5884.941150588504;5944.256967879015;6692.263850976413;6726.260111387744;6744.898826517606;6755.425788807957;6758.493113016543;6761.797146085625;6429.7106630201515;6609.339066093352;6669.23303820062;6670.799876003728;6671.799846004652;3914.843406263746;5419.214213938996;5715.742791574389;5895.764169433218;5935.465808077251;22001.339959801233;22712.545749084926;22860;23115.53768924612;23122.65316020261;2473.2810696565894;2495.5508008558454;2514.2959971208093;2731.1260396673074;2843.3602439450956;14101.78847317291;14106.788398174036;14127.71744565103;14438.422463101464;14607.70784584313;1986.6225417170835;2040.8265297449586;2041.428400047974;2046.1303945822965;2075.1491888325863;47778.28332575014;63279.367206327806;70971.2858926342;79570;84036;10535.84196237057;10915.126789856793;10958.890411095947;11049.226554141133;11064.944675276573;15053.096814191184;15173.317200725995;15180.240987950558;15208.16355100458;15212.391504339703;7877.881831772528;7886.408519361071;7958.482698624571;8141.837163256701;8193.180681931879;7600.315971562586;7615.466917315845;7659.731909383145;7767.1067827200595;7787.06555213378;6553.442957348583;6775.525713200029;6855.143107111611;6859.348361905611;6895.517313788087;1889.9811001889805;1954.355062829263;1958.392898201562;1968.7539057617798;1992.4520756791999;999.8150342186573;1019.4393083804026;1051.164324362134;1051.3008849115406;1133.1784456269304;18725.625487490175;18849.151788169293;19177.80822191788;19393.32123375675;19602.11790469432;1050.0497050169456;1069.256866477806;1095.8684957805115;1124.2018167101287;1166.7083229192701;10756.354618722746;11338.376389298668;11374.829377559343;11394.25937314072;11415.942920285346;483286;499283;500458;501746;505496;99657;101889;102655.48672256591;102863.4856825726;103394;977.1498796047484;1065.2543219746208;1115.1301984452302;1125.8480105185859;1131.688785583971;16516.091614960813;16888.66222675515;16893.66212675764;16928.40750573724;17140.742888856428;39034.21931561296;40141.59858401379;40144.7992760046;40404.191916160926;42386.57613423826;57746.711266445076;58363.4163658358;58445;59367.1094933585;59422.70288648529;8675.30597552207;8732.912670873464;8844.425112367675;8883.378163528749;9148.130927561871;8860.64557417696;12217.450214740536;12326.938365308117;12360.072994525626;12659.936700316439;10233.948830256099;10810.35137891729;11022.944885275523;11024.724381890517;11122.332660040423;10501.42242176657;10586.735331616463;10624.734381640521;10637.9468102659;10740.62407815754;10790.352578845295;10996.615118470816;11014.614488492864;11092;11179.66461006171;8510.74467765968;8776.692815751418;8888.333374996771;8994.05562415937;9090.36367454272;8402.663893444194;10526.684199474028;10932.945335273273;10964.287321324407;11191.608293709682 16:50:04 INFO - PROCESS | 12699 | |3;mootools.html;1448.717500087482;1589.9824112567956;1609.1310692226198;1820.8179182081794;1931.063434234397;9086.409383390075;9499.382540134886;9511.239100871931;9769.902300976988;9775.560099795508;9552.856707149398;9747.51262436877;9750.902490975088;9754.561044752985;9756;2444.7555244475543;2480.677511923456;2482.6276058591175;2483.956738169964;2484.3913241255827;2391.6532102845076;2412.0834083048417;2412.5657381671294;2414.915477958272;2416.8549887006748;2421.927342179729;2424.309071914505;2432.8661923594195;2433.2578563538204;2455.8772061396858;2335.4745182333922;2341.5785158671433;2342.3207269891627;2354.717433907942;2403.579373609629;2317.3974766560646;2354.7880690737743;2356.081128359941;2359.9528009439887;2361.8346715729904;8237.093919668832;9480.478573678445;9513.429194248367;9628.892677342124;9655.08276713711;5442.292501974757;5598.776048958037;5598.916016259759;5600.4959553639965;5608.4391560844;3454.4990976308295;3478.565179352581;3524.8766293179615;3525.735569832247;3636.9818150909077;1125.7410795516996;1169.9298042117416;1194.808830587102;1199.4182821331647;1211.0674780419115;14007.439702411892;14051.85948140526;14104.365303561364;14121.082129661641;14143.363548640336;4753.33453316536;4877.829275975395;4890.804367825318;4896.265560165933;4965.180745177077;11615.76768464626;11642.70893227676;11650.126240531994;11731;11769.352685602204;5618.943810561924;5625.915611265835;5628.662280263197;5632.464915832989;5633.746481408347;4979.053979743837;5060.5698515625845;5166.250893620404;5172.586193104543;5195.402528709171;3908.101136738566;3961.6830653547645;3984.5816189300285;4014.397840323975;4046.251443482965;1208.697825543614;1215.7629262293767;1219.7682440336307;1219.9024078073733;1220.8046712526132;1001.3491230700104;1005.1657124586576;1010.5099026971913;1020.8366661334151;1025.8563801067855;6717.429018533382;6726.697298621476;6746.460283177334;6752.966235168793;6753.054572359873;436.44135506551663;453.8184726109583;457.922153233951;464.7583256706561;468.44021394434003;4432.268675668479;4485;4493.528179541101;4529.796159172845;4537.410136682276;11946;12349.259044457362;12351.444185011516;12366.876331236752;12395.256284622952;11737.35444550558;12148.5140594377;12155.149139560146;12274.509019639114;12486.313252771184;9084;9186.356955013085;9203.585838637277;9210.447373157633;9218.44689318643;6616.603003819786;6814.420774234246;6874.793756187322;6937.340952609494;7014.053102831256;11203.663890083311;11235.494402751732;11419.942900285445;11483.081353491534;11485.425728713364;11526.481308340977;11705.122115841175;11720.765584688428;11727.413629318671;11776.882231177578;8531.146885311418;8539.573021348784;8560.334468752782;8645.913540864762;8662.480251184948;9226.031266716898;9248.815023699623;9251;9282.62869485213;9322.906770932204;8490.23587877094;8514.276286515467;8522.36082293818;8620.52587107715;8626.094260102598;8089.9191008089165;8188.058373287148;8268.049174344787;8292.419530632798;8297.336213102817;7421.740239091606;7425.368843648351;7449.366803821736;7450.776476705708;7453.850922981618;5503.20203570472;5593.580481463825;5670.262865827416;5751.166080918327;5754.942450575608;5627.859303517281;5672.177534257592;5677.716114194356;5730.770769169353;5876.265466816648 16:50:04 INFO - PROCESS | 12699 | |4;prototype.html;315.94628913084773;322.79986408426777;322.96285927118373;332.95172200031;340.6916740349984;5298.417174110845;5466.289382380295;5469.425710300421;5525.723713814314;5535.69911070898;5325.600579956509;5402.163264490073;5408.567314614831;5442.292501974738;5458.61789674723;4234.136979728924;4251;4255.468066491689;4258.765767882765;4269.551697071809;3998.4602078719436;4087.713860029799;4226.4716910386205;4228.429162063113;4258.510271318807;4166.54171040135;4180.5610410906875;4186.74879507229;4193.2242535130945;4201.096764195698;4181.644560212386;4193.811278492475;4195.160967806421;4213.747175169499;4217.873463796091;3637.5453068366455;3698.8335524901177;3713.3687273163628;3721.6836568891677;3742.158014446755;5193.454687257859;5203.635745497817;5230.764615592308;5245.554127899133;5261.210818377236;4814.7351895645725;4847.854564363075;4849.975750121227;4878.902072108992;4885.193942999401;5437.945620543823;5674.432556744293;5699.401562835884;5699.68651724155;5718.42815718429;4068.2270368629866;4088.9386659200136;4091.977005748563;4098.344264917629;4102.364133559273;5844.357120716761;5886.440788125121;5913.408659134096;5988.892054969844;5993.932093427804;4077.571854955187;4246.639035682002;4268.9786551067045;4273.594008569181;4286.764227967429;4298.591633794785;4306.956930430656;4312.813976156582;4314.784260786948;4324.200022995756;4868.318435419044;4873.780679869415;4887.902241955141;4891.730954797449;4907.533784290486;5624.943750562442;5726.627769194989;5771.451712087345;5776.480116789509;5782.2194003808945;1396.273937552467;1443.1990245413685;1478.519481168603;1485.695432436348;1491.135141617859;944.4003058058182;950.5294879034784;964.6671898195111;966.0966995858895;977.9145148884733;4935.851924442272;4952.390473096259;4974.502549744997;4975.676581022222;5025.969676216367;5176.404713457925;5182;5205.583553315726;5224.831694846672;5283.603729720209;4696.553827386393;5003.424606170337;5056.696598204046;5073.923891141636;5075.898482030412;2737.658547311803;2774.5838124281117;2802.2574017885277;2811.6063751074867;2814.1426658405353;5354.384245811703;5394.811181608703;5400.83797486076;5408.080131553242;5437.619366644297;5081.28861959326;5265.631405801557;5276.973615131977;5304.628675992721;5312.654677445954;5349;5401.864953376197;5410;5418.187271909166;5430.262316058824;5194.2858856578;5194.636375453682;5245.527902488641;5253;5257.237700533477;4810.103815908985;5122.513361230677;5154.871128221825;5168.5528052145455;5178.870528236674;5399.568034557305;5403.729813509387;5405.972970135282;5408.349908544863;5422.9186562202385;3867.1878905430176;3877.3990031545154;3883.165119499321;3888.9416658750706;3897.006263402814;4867.90264194707;5198.2202669599865;5256.6583172095225;5643.657881978933;5754.223179870663;5717.714114294352;5724.255846740064;5725.914111288251;5726.455986681259;5736.397678243724;5694.231278777478;5704;5752.9712351437975;5784.392638772868;5798.362180160095;5511.448855114457;5655;5661.150827375926;5696.8006119785605;5699.629524080921;5735.397783232867;5785.161151632906;5786.42135786418;5794.681292528951;5841.649501029951;5467.343918729778;5688.317401911797;5699.914501282401;5705.630648644213;5720.141978703228 16:50:04 INFO - PROCESS | 12699 | |5;yui.html;751.1511991449661;760.2397602397599;804.3524962405262;817.7546735979208;821.1419067074906;14376.49682261121;14413.927930360334;14420.783688244685;14440.638984025418;14593;14002.649933751662;14064.859351406483;14073.507427240049;14084.436622535111;14085.647858803535;692.4875592061888;823.4318320358963;845.5349557743248;861.1216559109704;865.4547634989979;862.4006315610653;868.5179725252482;871.4074429388014;871.8735783311416;876.0713643537838;909.0137201136774;910.9590068446903;914.5838643417262;915.9313051521129;917.6788124156525;723.2478222648439;868.9522076285801;902.201551626815;903.6566104880136;905.4612505559181;670.8222321084917;674.834665506949;674.9358810912955;675.5710124071201;675.5845155229548;13341.86658133416;13402.396892139877;13408.061435699505;13410.798838017437;13438.193708377528;14300.141991480441;14646.41414343425;15050.548483545512;15084.849151508453;15229.086254824635;12324.753504929851;12612.558560450432;13128;13226;13270.734585308337;4399.186150562156;4439.578240067189;4444.644428445717;4457.554244575549;4516.932246016312;74410.62794686046;74724;75781.62109189473;77864.2213577857;78500.60749696272;11373.94313028446;11495.597654082067;11641.47613357401;11658.41707914601;11924.88075119255;23132.7686723134;23300.417489562897;23361.41596460068;23397.53204935926;23437;8239.134890836496;8354.624041918129;8357.95821020891;8701.17338852808;8808.471491710518;4706.388169537938;4779.928301075487;4836.250381190921;4907.288443175719;4961.330220420197;3707.351213537674;3788.109794198361;3792.146766977408;3794.734368594172;3799.4490798834013;579.2990481517411;606.3148642034473;609.1289456077851;622.327885883245;700.1912790726617;669.7923643670478;710;794.2137284088826;809.0938149272852;809.6599428240153;9556.139947404628;9744.464054477072;9905.752356191153;10076;10093;656.2879275985609;661.4840424468922;661.751843058853;666.7133132752866;691.1602403080282;4709.95290047102;4725.503822098699;4738;4793.065352256276;4839.056384005085;77591.61204193943;124146;125587.3720631391;127520.36239818741;128511.35744321406;66563.66718166474;67764;79538;104070.47964760127;106919.46540267249;93064.53467732618;94747.52626236825;94903.52548237077;96733;98542;9146.359754817098;9195.816083678423;9304.395214311047;9305.02297258805;9310.627574896927;43003.784981074896;46734.53265467438;57525.424745753684;57563.71218143882;58068;48973;70093.64953175202;71557.28442715506;73724;73819.26180738339;12254.326012069065;12349.19730217533;12379.69050773738;12779.61661150167;13000.414981326052;14271.500497482539;14482.275886205436;14701.779473308123;14760.48338308154;14985.550433487013;13243.271620060605;13710;13788.93105534506;13871.514496992557;13889.305534723426;13106.868931310946;13163.73672526525;13298.135621184592;13435.328233588087;13638.795418068936;13301.600951971457;13548.525801596898;13904.165750055028;14095.224762638154;14185.36165872559;10918.290311129751;11289.153313501685;11304.321740695585;11436.885631143583;11465.828012579985;11397.829032564685;11546.76906461883;11570;11693.824592631288;11891.28652280866 16:50:04 INFO - PROCESS | 12699 | __end_tp_report 16:50:04 INFO - PROCESS | 12699 | __start_cc_report 16:50:04 INFO - PROCESS | 12699 | _x_x_mozilla_cycle_collect,798 16:50:04 INFO - PROCESS | 12699 | __end_cc_report 16:50:04 INFO - PROCESS | 12699 | __startTimestamp1470095404948__endTimestamp 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | ------- Summary: start ------- 16:50:04 INFO - PROCESS | 12699 | Number of tests: 6 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | [#0] dojo.html Cycles:165 Average:5444.90 Median:5648.69 stddev:598.52 (10.6%) stddev-sans-first:576.63 16:50:04 INFO - PROCESS | 12699 | Values: 3318.0 3332.7 3340.7 3343.3 3367.4 5380.9 5447.7 5469.5 5480.8 5512.2 5371.8 5475.4 5494.1 5497.5 5536.1 4830.9 4852.3 4901.0 4929.4 4968.3 4873.7 4894.3 4929.7 4943.6 4963.2 4900.7 4906.9 4934.0 4978.6 4979.5 4964.8 4990.6 4991.6 4991.8 5016.3 4622.1 4785.0 4893.0 4920.5 4923.2 5460.0 5469.5 5488.7 5494.5 5508.6 5938.3 5973.4 5985.3 5992.4 5992.9 5673.4 5759.6 5761.9 5954.5 6032.3 5518.7 5522.9 5523.9 5527.1 5533.2 6017.2 6023.0 6026.5 6029.9 6038.5 5629.3 5681.5 5725.8 5739.9 5779.3 5871.6 5872.4 5909.0 5914.7 5920.3 5776.2 5805.4 5816.3 5822.0 5833.1 5636.5 5715.8 5790.8 5795.0 5833.4 4555.7 4564.7 4567.7 4575.7 4591.6 4016.7 4085.7 4103.7 4124.1 4129.9 5543.4 5565.3 5607.4 5613.5 5641.4 5883.1 5906.9 5907.4 5910.6 5945.3 5484.7 5491.3 5538.5 5579.1 5653.5 5048.3 5065.0 5071.3 5074.9 5082.4 6050.6 6051.2 6061.0 6062.2 6068.8 5686.9 5789.2 5804.1 5839.2 5887.6 6114.9 6139.5 6141.5 6143.2 6168.6 5787.7 5792.4 5793.0 5800.0 5818.4 5691.3 5721.8 5735.4 5752.8 5881.9 5641.5 5676.5 5679.8 5690.2 5690.3 5603.7 5661.5 5667.2 5673.9 5674.5 5543.4 5555.1 5618.1 5648.7 5698.1 5817.3 5824.5 5849.8 5859.9 5873.9 5509.8 5702.3 5709.5 5718.8 5740.9 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | [#1] ext.html Cycles:180 Average:61229.05 Median:37350.22 stddev:120432.83 (322.4%) stddev-sans-first:120684.65 16:50:04 INFO - PROCESS | 12699 | Values: 601.8 642.6 653.4 658.7 666.8 32998.5 45279.5 45926.0 45982.3 46040.8 48256.3 48967.5 48989.0 49013.0 49041.0 8490.6 9154.8 9286.0 10747.6 11006.1 11009.0 11104.9 11114.7 11117.9 11125.7 11165.8 11212.3 11213.1 11216.6 11223.5 11047.4 11082.8 11095.3 11101.7 11105.1 11057.2 11087.5 11116.6 11117.6 11125.1 44141.8 44247.0 44455.6 44552.1 44578.8 3570.3 3617.5 4250.3 4276.7 4362.3 34070.5 34395.0 34456.3 34470.5 34552.8 4678.3 4686.7 4766.0 4766.1 4846.8 361770.0 362303.0 362595.2 363021.0 364387.0 37840.6 63047.0 63302.7 63619.4 63651.7 37600.8 39356.6 39370.8 39371.0 39424.2 13981.3 14169.7 14172.1 14173.0 14418.9 11393.9 11556.1 11617.3 11791.2 11805.6 9215.0 9254.1 9261.5 9318.8 9324.0 3532.3 3593.5 3605.6 3615.1 3669.0 1075.2 1093.8 1102.6 1125.5 1198.9 34325.8 34394.0 34506.0 34591.7 34664.8 1187.5 1208.0 1252.1 1267.6 1287.3 17788.2 17931.7 18082.0 18151.4 18162.8 557034.0 558074.0 559232.0 560979.0 562294.0 63871.7 64396.0 64415.4 64427.7 64468.0 1355.8 1403.2 1445.9 1459.9 1484.9 30927.8 33647.3 33807.7 33986.3 34643.3 53324.7 53801.2 54045.0 54055.5 54143.5 415454.0 416228.0 416278.0 418059.0 419173.0 26766.9 30127.7 30651.4 30767.8 30982.0 51681.7 57441.4 57964.4 58267.7 58805.4 44165.6 44374.0 44710.0 45330.8 45865.8 43954.8 44341.6 44540.8 44682.0 45801.8 44023.3 44467.8 44980.0 45092.5 45436.3 37099.6 39147.0 40096.2 40124.6 40342.4 25739.5 25892.4 26047.5 26119.6 26371.0 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | [#2] jquery.html Cycles:180 Average:29224.95 Median:22356.94 stddev:82136.39 (367.4%) stddev-sans-first:82338.34 16:50:04 INFO - PROCESS | 12699 | Values: 424.7 427.4 427.8 428.6 443.2 18313.1 19082.6 19660.3 19694.1 20040.6 18282.5 18416.4 18962.7 19035.4 19114.2 5683.9 5871.0 5903.9 5909.3 5915.3 4297.7 5214.0 5884.9 5944.3 6692.3 6726.3 6744.9 6755.4 6758.5 6761.8 6429.7 6609.3 6669.2 6670.8 6671.8 3914.8 5419.2 5715.7 5895.8 5935.5 22001.3 22712.5 22860.0 23115.5 23122.7 2473.3 2495.6 2514.3 2731.1 2843.4 14101.8 14106.8 14127.7 14438.4 14607.7 1986.6 2040.8 2041.4 2046.1 2075.1 47778.3 63279.4 70971.3 79570.0 84036.0 10535.8 10915.1 10958.9 11049.2 11064.9 15053.1 15173.3 15180.2 15208.2 15212.4 7877.9 7886.4 7958.5 8141.8 8193.2 7600.3 7615.5 7659.7 7767.1 7787.1 6553.4 6775.5 6855.1 6859.3 6895.5 1890.0 1954.4 1958.4 1968.8 1992.5 999.8 1019.4 1051.2 1051.3 1133.2 18725.6 18849.2 19177.8 19393.3 19602.1 1050.0 1069.3 1095.9 1124.2 1166.7 10756.4 11338.4 11374.8 11394.3 11415.9 483286.0 499283.0 500458.0 501746.0 505496.0 99657.0 101889.0 102655.5 102863.5 103394.0 977.1 1065.3 1115.1 1125.8 1131.7 16516.1 16888.7 16893.7 16928.4 17140.7 39034.2 40141.6 40144.8 40404.2 42386.6 57746.7 58363.4 58445.0 59367.1 59422.7 8675.3 8732.9 8844.4 8883.4 9148.1 8860.6 12217.5 12326.9 12360.1 12659.9 10233.9 10810.4 11022.9 11024.7 11122.3 10501.4 10586.7 10624.7 10637.9 10740.6 10790.4 10996.6 11014.6 11092.0 11179.7 8510.7 8776.7 8888.3 8994.1 9090.4 8402.7 10526.7 10932.9 10964.3 11191.6 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | [#3] mootools.html Cycles:180 Average:6355.32 Median:2495.61 stddev:3780.97 (151.5%) stddev-sans-first:3773.60 16:50:04 INFO - PROCESS | 12699 | Values: 1448.7 1590.0 1609.1 1820.8 1931.1 9086.4 9499.4 9511.2 9769.9 9775.6 9552.9 9747.5 9750.9 9754.6 9756.0 2444.8 2480.7 2482.6 2484.0 2484.4 2391.7 2412.1 2412.6 2414.9 2416.9 2421.9 2424.3 2432.9 2433.3 2455.9 2335.5 2341.6 2342.3 2354.7 2403.6 2317.4 2354.8 2356.1 2360.0 2361.8 8237.1 9480.5 9513.4 9628.9 9655.1 5442.3 5598.8 5598.9 5600.5 5608.4 3454.5 3478.6 3524.9 3525.7 3637.0 1125.7 1169.9 1194.8 1199.4 1211.1 14007.4 14051.9 14104.4 14121.1 14143.4 4753.3 4877.8 4890.8 4896.3 4965.2 11615.8 11642.7 11650.1 11731.0 11769.4 5618.9 5625.9 5628.7 5632.5 5633.7 4979.1 5060.6 5166.3 5172.6 5195.4 3908.1 3961.7 3984.6 4014.4 4046.3 1208.7 1215.8 1219.8 1219.9 1220.8 1001.3 1005.2 1010.5 1020.8 1025.9 6717.4 6726.7 6746.5 6753.0 6753.1 436.4 453.8 457.9 464.8 468.4 4432.3 4485.0 4493.5 4529.8 4537.4 11946.0 12349.3 12351.4 12366.9 12395.3 11737.4 12148.5 12155.1 12274.5 12486.3 9084.0 9186.4 9203.6 9210.4 9218.4 6616.6 6814.4 6874.8 6937.3 7014.1 11203.7 11235.5 11419.9 11483.1 11485.4 11526.5 11705.1 11720.8 11727.4 11776.9 8531.1 8539.6 8560.3 8645.9 8662.5 9226.0 9248.8 9251.0 9282.6 9322.9 8490.2 8514.3 8522.4 8620.5 8626.1 8089.9 8188.1 8268.0 8292.4 8297.3 7421.7 7425.4 7449.4 7450.8 7453.9 5503.2 5593.6 5670.3 5751.2 5754.9 5627.9 5672.2 5677.7 5730.8 5876.3 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | [#4] prototype.html Cycles:180 Average:4626.20 Median:5194.46 stddev:1334.66 (25.7%) stddev-sans-first:1298.60 16:50:04 INFO - PROCESS | 12699 | Values: 315.9 322.8 323.0 333.0 340.7 5298.4 5466.3 5469.4 5525.7 5535.7 5325.6 5402.2 5408.6 5442.3 5458.6 4234.1 4251.0 4255.5 4258.8 4269.6 3998.5 4087.7 4226.5 4228.4 4258.5 4166.5 4180.6 4186.7 4193.2 4201.1 4181.6 4193.8 4195.2 4213.7 4217.9 3637.5 3698.8 3713.4 3721.7 3742.2 5193.5 5203.6 5230.8 5245.6 5261.2 4814.7 4847.9 4850.0 4878.9 4885.2 5437.9 5674.4 5699.4 5699.7 5718.4 4068.2 4088.9 4092.0 4098.3 4102.4 5844.4 5886.4 5913.4 5988.9 5993.9 4077.6 4246.6 4269.0 4273.6 4286.8 4298.6 4307.0 4312.8 4314.8 4324.2 4868.3 4873.8 4887.9 4891.7 4907.5 5624.9 5726.6 5771.5 5776.5 5782.2 1396.3 1443.2 1478.5 1485.7 1491.1 944.4 950.5 964.7 966.1 977.9 4935.9 4952.4 4974.5 4975.7 5026.0 5176.4 5182.0 5205.6 5224.8 5283.6 4696.6 5003.4 5056.7 5073.9 5075.9 2737.7 2774.6 2802.3 2811.6 2814.1 5354.4 5394.8 5400.8 5408.1 5437.6 5081.3 5265.6 5277.0 5304.6 5312.7 5349.0 5401.9 5410.0 5418.2 5430.3 5194.3 5194.6 5245.5 5253.0 5257.2 4810.1 5122.5 5154.9 5168.6 5178.9 5399.6 5403.7 5406.0 5408.3 5422.9 3867.2 3877.4 3883.2 3888.9 3897.0 4867.9 5198.2 5256.7 5643.7 5754.2 5717.7 5724.3 5725.9 5726.5 5736.4 5694.2 5704.0 5753.0 5784.4 5798.4 5511.4 5655.0 5661.2 5696.8 5699.6 5735.4 5785.2 5786.4 5794.7 5841.6 5467.3 5688.3 5699.9 5705.6 5720.1 16:50:04 INFO - PROCESS | 12699 | 16:50:04 INFO - PROCESS | 12699 | [#5] yui.html Cycles:180 Average:20602.66 Median:4708.17 stddev:29827.99 (633.5%) stddev-sans-first:29874.42 16:50:04 INFO - PROCESS | 12699 | Values: 751.2 760.2 804.4 817.8 821.1 14376.5 14413.9 14420.8 14440.6 14593.0 14002.6 14064.9 14073.5 14084.4 14085.6 692.5 823.4 845.5 861.1 865.5 862.4 868.5 871.4 871.9 876.1 909.0 911.0 914.6 915.9 917.7 723.2 869.0 902.2 903.7 905.5 670.8 674.8 674.9 675.6 675.6 13341.9 13402.4 13408.1 13410.8 13438.2 14300.1 14646.4 15050.5 15084.8 15229.1 12324.8 12612.6 13128.0 13226.0 13270.7 4399.2 4439.6 4444.6 4457.6 4516.9 74410.6 74724.0 75781.6 77864.2 78500.6 11373.9 11495.6 11641.5 11658.4 11924.9 23132.8 23300.4 23361.4 23397.5 23437.0 8239.1 8354.6 8358.0 8701.2 8808.5 4706.4 4779.9 4836.3 4907.3 4961.3 3707.4 3788.1 3792.1 3794.7 3799.4 579.3 606.3 609.1 622.3 700.2 669.8 710.0 794.2 809.1 809.7 9556.1 9744.5 9905.8 10076.0 10093.0 656.3 661.5 661.8 666.7 691.2 4710.0 4725.5 4738.0 4793.1 4839.1 77591.6 124146.0 125587.4 127520.4 128511.4 66563.7 67764.0 79538.0 104070.5 106919.5 93064.5 94747.5 94903.5 96733.0 98542.0 9146.4 9195.8 9304.4 9305.0 9310.6 43003.8 46734.5 57525.4 57563.7 58068.0 48973.0 70093.6 71557.3 73724.0 73819.3 12254.3 12349.2 12379.7 12779.6 13000.4 14271.5 14482.3 14701.8 14760.5 14985.6 13243.3 13710.0 13788.9 13871.5 13889.3 13106.9 13163.7 13298.1 13435.3 13638.8 13301.6 13548.5 13904.2 14095.2 14185.4 10918.3 11289.2 11304.3 11436.9 11465.8 11397.8 11546.8 11570.0 11693.8 11891.3 16:50:04 INFO - PROCESS | 12699 | -------- Summary: end -------- 16:50:04 INFO - PROCESS | 12699 | 16:50:05 INFO - TEST-INFO | 12699: exit 0 16:50:05 INFO - TEST-OK | dromaeo_css | took 1112281ms 16:50:05 INFO - TEST-START | kraken 16:50:05 INFO - operating with platform_type : linux_ 16:50:05 INFO - Initialising browser for kraken test... 16:50:05 INFO - TEST-INFO | started process 12893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLMlw4k/profile http://localhost:36177/getInfo.html) 16:50:10 INFO - PROCESS | 12893 | __metrics Screen width/height:1600/1200 16:50:10 INFO - PROCESS | 12893 | colorDepth:24 16:50:10 INFO - PROCESS | 12893 | Browser inner width/height: 1024/697 16:50:10 INFO - PROCESS | 12893 | __metrics 16:50:11 INFO - PROCESS | 12893 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:50:11 ERROR - PROCESS | 12893 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:50:11 INFO - TEST-INFO | 12893: exit 0 16:50:11 INFO - Browser initialized. 16:50:11 INFO - Running cycle 1/1 for kraken test... 16:50:11 INFO - TEST-INFO | started process 12989 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLMlw4k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:50:23 INFO - PROCESS | 12989 | RSS: Main: 145465344 16:50:23 INFO - PROCESS | 12989 | 16:50:26 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36177/tests/kraken/driver.html?testName=audio-beat-detection) 16:50:27 INFO - PROCESS | 12989 | RSS: Main: 195850240 16:50:27 INFO - PROCESS | 12989 | 16:50:29 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36177/tests/kraken/driver.html?testName=audio-dft) 16:50:30 INFO - PROCESS | 12989 | RSS: Main: 194306048 16:50:30 INFO - PROCESS | 12989 | 16:50:33 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36177/tests/kraken/driver.html?testName=audio-fft) 16:50:33 INFO - PROCESS | 12989 | RSS: Main: 281223168 16:50:33 INFO - PROCESS | 12989 | 16:50:35 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36177/tests/kraken/driver.html?testName=audio-oscillator) 16:50:36 INFO - PROCESS | 12989 | RSS: Main: 189878272 16:50:36 INFO - PROCESS | 12989 | 16:50:38 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36177/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:50:39 INFO - PROCESS | 12989 | RSS: Main: 189825024 16:50:39 INFO - PROCESS | 12989 | 16:50:42 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36177/tests/kraken/driver.html?testName=imaging-darkroom) 16:50:42 INFO - PROCESS | 12989 | RSS: Main: 224698368 16:50:42 INFO - PROCESS | 12989 | 16:50:45 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36177/tests/kraken/driver.html?testName=imaging-desaturate) 16:50:46 INFO - PROCESS | 12989 | RSS: Main: 224661504 16:50:46 INFO - PROCESS | 12989 | 16:50:49 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36177/tests/kraken/driver.html?testName=json-parse-financial) 16:50:49 INFO - PROCESS | 12989 | RSS: Main: 224649216 16:50:49 INFO - PROCESS | 12989 | 16:50:51 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36177/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:50:51 INFO - PROCESS | 12989 | RSS: Main: 202170368 16:50:51 INFO - PROCESS | 12989 | 16:50:53 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:50:54 INFO - PROCESS | 12989 | RSS: Main: 204468224 16:50:54 INFO - PROCESS | 12989 | 16:50:56 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:50:56 INFO - PROCESS | 12989 | RSS: Main: 207863808 16:50:56 INFO - PROCESS | 12989 | 16:50:59 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:00 INFO - PROCESS | 12989 | RSS: Main: 214724608 16:51:00 INFO - PROCESS | 12989 | 16:51:02 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:51:03 INFO - PROCESS | 12989 | RSS: Main: 216203264 16:51:03 INFO - PROCESS | 12989 | 16:51:04 INFO - PROCESS | 12989 | Cycle 1(1): loaded http://localhost:36177/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36177/tests/kraken/driver.html?testName=ai-astar) 16:51:05 INFO - PROCESS | 12989 | RSS: Main: 257953792 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | __start_tp_report 16:51:05 INFO - PROCESS | 12989 | _x_x_mozilla_page_load 16:51:05 INFO - PROCESS | 12989 | _x_x_mozilla_page_load_details 16:51:05 INFO - PROCESS | 12989 | |i|pagename|runs| 16:51:05 INFO - PROCESS | 12989 | |0;ai-astar;111;105;110;110;110;111;110;112;112;110 16:51:05 INFO - PROCESS | 12989 | |1;audio-beat-detection;149;122;123;177;124;123;158;124;170;129 16:51:05 INFO - PROCESS | 12989 | |2;audio-dft;160;162;162;161;161;161;162;161;161;160 16:51:05 INFO - PROCESS | 12989 | |3;audio-fft;73;75;76;100;76;75;75;75;107;74 16:51:05 INFO - PROCESS | 12989 | |4;audio-oscillator;84;84;113;84;84;84;116;85;84;125 16:51:05 INFO - PROCESS | 12989 | |5;imaging-gaussian-blur;117;117;116;116;116;116;116;116;117;117 16:51:05 INFO - PROCESS | 12989 | |6;imaging-darkroom;145;146;145;144;145;144;145;145;145;144 16:51:05 INFO - PROCESS | 12989 | |7;imaging-desaturate;94;95;95;95;96;95;95;95;95;95 16:51:05 INFO - PROCESS | 12989 | |8;json-parse-financial;51;50;51;53;52;52;52;52;52;54 16:51:05 INFO - PROCESS | 12989 | |9;json-stringify-tinderbox;60;61;59;60;60;59;61;59;60;60 16:51:05 INFO - PROCESS | 12989 | |10;stanford-crypto-aes;77;77;76;82;81;80;77;77;110;83 16:51:05 INFO - PROCESS | 12989 | |11;stanford-crypto-ccm;126;117;115;124;125;117;117;119;140;128 16:51:05 INFO - PROCESS | 12989 | |12;stanford-crypto-pbkdf2;157;156;153;153;156;156;167;160;155;154 16:51:05 INFO - PROCESS | 12989 | |13;stanford-crypto-sha256-iterative;63;62;66;62;63;63;61;65;62;60 16:51:05 INFO - PROCESS | 12989 | __end_tp_report 16:51:05 INFO - PROCESS | 12989 | __start_cc_report 16:51:05 INFO - PROCESS | 12989 | _x_x_mozilla_cycle_collect,1534 16:51:05 INFO - PROCESS | 12989 | __end_cc_report 16:51:05 INFO - PROCESS | 12989 | __startTimestamp1470095465135__endTimestamp 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | ------- Summary: start ------- 16:51:05 INFO - PROCESS | 12989 | Number of tests: 14 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.50 stddev:1.97 (1.8%) stddev-sans-first:2.06 16:51:05 INFO - PROCESS | 12989 | Values: 111.0 105.0 110.0 110.0 110.0 111.0 110.0 112.0 112.0 110.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:139.00 stddev:21.63 (15.6%) stddev-sans-first:22.69 16:51:05 INFO - PROCESS | 12989 | Values: 149.0 122.0 123.0 177.0 124.0 123.0 158.0 124.0 170.0 129.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 16:51:05 INFO - PROCESS | 12989 | Values: 160.0 162.0 162.0 161.0 161.0 161.0 162.0 161.0 161.0 160.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.00 stddev:12.21 (16.3%) stddev-sans-first:12.64 16:51:05 INFO - PROCESS | 12989 | Values: 73.0 75.0 76.0 100.0 76.0 75.0 75.0 75.0 107.0 74.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:84.00 stddev:16.62 (19.8%) stddev-sans-first:17.21 16:51:05 INFO - PROCESS | 12989 | Values: 84.0 84.0 113.0 84.0 84.0 84.0 116.0 85.0 84.0 125.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:51:05 INFO - PROCESS | 12989 | Values: 117.0 117.0 116.0 116.0 116.0 116.0 116.0 116.0 117.0 117.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#6] imaging-darkroom Cycles:10 Average:144.80 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 16:51:05 INFO - PROCESS | 12989 | Values: 145.0 146.0 145.0 144.0 145.0 144.0 145.0 145.0 145.0 144.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 16:51:05 INFO - PROCESS | 12989 | Values: 94.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.10 (2.1%) stddev-sans-first:1.12 16:51:05 INFO - PROCESS | 12989 | Values: 51.0 50.0 51.0 53.0 52.0 52.0 52.0 52.0 52.0 54.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 16:51:05 INFO - PROCESS | 12989 | Values: 60.0 61.0 59.0 60.0 60.0 59.0 61.0 59.0 60.0 60.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:78.50 stddev:10.14 (12.9%) stddev-sans-first:10.60 16:51:05 INFO - PROCESS | 12989 | Values: 77.0 77.0 76.0 82.0 81.0 80.0 77.0 77.0 110.0 83.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#11] stanford-crypto-ccm Cycles:10 Average:122.80 Median:124.50 stddev:7.57 (6.1%) stddev-sans-first:7.94 16:51:05 INFO - PROCESS | 12989 | Values: 126.0 117.0 115.0 124.0 125.0 117.0 117.0 119.0 140.0 128.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:156.00 stddev:4.16 (2.7%) stddev-sans-first:4.42 16:51:05 INFO - PROCESS | 12989 | Values: 157.0 156.0 153.0 153.0 156.0 156.0 167.0 160.0 155.0 154.0 16:51:05 INFO - PROCESS | 12989 | 16:51:05 INFO - PROCESS | 12989 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.77 (2.8%) stddev-sans-first:1.87 16:51:05 INFO - PROCESS | 12989 | Values: 63.0 62.0 66.0 62.0 63.0 63.0 61.0 65.0 62.0 60.0 16:51:05 INFO - PROCESS | 12989 | -------- Summary: end -------- 16:51:05 INFO - PROCESS | 12989 | 16:51:06 INFO - TEST-INFO | 12989: exit 0 16:51:06 INFO - TEST-OK | kraken | took 60478ms 16:51:06 INFO - SUITE-END | took 1172s 16:51:06 INFO - Completed test suite (00:19:32) 16:51:06 INFO - javascript benchmark 16:51:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.971428857054, 3332.70005699487, 3340.7327413806897, 3343.2644818140006, 3367.4275373186556, 5380.946190538099, 5447.727613619313, 5469.453054694529, 5480.808171713991, 5512.173174023898, 5371.75827087781, 5475.397706252309, 5494.09347457669, 5497.505224529791, 5536.141898005805, 4830.9275360869615, 4852.320675105488, 4901.044296362202, 4929.408470983487, 4968.254761785743, 4873.683210591318, 4894.290327902452, 4929.728864912428, 4943.604511639078, 4963.156263435207, 4900.705957642535, 4906.9263961040615, 4933.950660493403, 4978.626603004771, 4979.526944940225, 4964.801407943678, 4990.550850423461, 4991.550760431579, 4991.7753701083175, 5016.3227964224725, 4622.14490319292, 4784.952150478485, 4892.9510704892855, 4920.507949205086, 4923.236898280772, 5459.972700136514, 5469.480399362054, 5488.670679759227, 5494.450554944514, 5508.64193827404, 5938.317093534212, 5973.402659733992, 5985.34161242263, 5992.43071908168, 5992.940070599282, 5673.432656734335, 5759.5968282220265, 5761.942380576224, 5954.4938680212235, 6032.306284777263, 5518.668879867221, 5522.889542209134, 5523.889522209573, 5527.143292789624, 5533.1976863354585, 6017.247844019497, 6023.0, 6026.5178785697035, 6029.879402411927, 6038.486728628116, 5629.268195134607, 5681.460261275172, 5725.77096916127, 5739.942600573941, 5779.306483221956, 5871.55963302754, 5872.412758724179, 5909.0, 5914.733836977346, 5920.348761636216, 5776.1624564437925, 5805.390434004453, 5816.302043754692, 5821.970890145522, 5833.066709326487, 5636.520895723827, 5715.799947001835, 5790.768369265183, 5795.0, 5833.358330583633, 4555.749433781108, 4564.748938808331, 4567.725936443824, 4575.656825738083, 4591.632669386441, 4016.7188296819527, 4085.6935729820384, 4103.692223083281, 4124.13393187428, 4129.876103716893, 5543.362513311021, 5565.30433695788, 5607.43925607436, 5613.522850557667, 5641.407652196543, 5883.117532370179, 5906.911396329058, 5907.409259074058, 5910.615809972338, 5945.256842894638, 5484.670919744828, 5491.341039075257, 5538.529225015838, 5579.079451890392, 5653.491185793298, 5048.3184770056305, 5065.037642847848, 5071.340725705634, 5074.898502030012, 5082.440931497532, 6050.576459647782, 6051.183090282843, 6061.030235162353, 6062.242219722535, 6068.787592434332, 5686.857828554319, 5789.169933729964, 5804.071348584206, 5839.182514448065, 5887.558433117448, 6114.905143643943, 6139.522803442411, 6141.539384546266, 6143.201383820075, 6168.568200225941, 5787.681677507778, 5792.420757924174, 5793.0, 5799.971000144972, 5818.359980402068, 5691.260136182435, 5721.828345149652, 5735.3977832327, 5752.798652047159, 5881.882362352814, 5641.464060914371, 5676.460736230051, 5679.801206957736, 5690.231818704422, 5690.317161940594, 5603.663780173202, 5661.46216109469, 5667.234923285304, 5673.886522269614, 5674.4892959633835, 5543.445655434424, 5555.055640541199, 5618.073017952155, 5648.689322087324, 5698.1452782083015, 5817.301923769006, 5824.5340372770925, 5849.766009359578, 5859.882802344015, 5873.853153671193, 5509.834704958857, 5702.258706368146, 5709.457601527848, 5718.828435146952, 5740.942590574041], "value": 5406.9236676426635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.7833579911234, 642.6304874697049, 653.4380432827767, 658.720043981308, 666.8399584099811, 32998.50502242468, 45279.547204527946, 45926.0, 45982.31026534601, 46040.76979615097, 48256.27615585769, 48967.51032489674, 48989.0, 49013.0, 49041.0, 8490.617922193516, 9154.81690366194, 9286.0, 10747.570097196103, 11006.119510439186, 11009.0, 11104.888951110466, 11114.722131946726, 11117.888821111806, 11125.666230013112, 11165.83251251232, 11212.271202371861, 11213.10295176388, 11216.607418740346, 11223.494942727597, 11047.447627618627, 11082.778344433107, 11095.334279943148, 11101.72245693856, 11105.056070234039, 11057.225994180411, 11087.50106245221, 11116.61091861781, 11117.610883619116, 11125.109991200685, 44141.77929110366, 44247.0, 44455.55544444547, 44552.10895782099, 44578.77710611458, 3570.250247448039, 3617.5478065241846, 4250.298700714378, 4276.700630955835, 4362.345648152771, 34070.48894266588, 34395.0, 34456.31087378263, 34470.48294275588, 34552.82723586391, 4678.345031695565, 4686.742229177394, 4765.975315307225, 4766.142094423002, 4846.806127754886, 361770.0, 362303.0, 362595.1870240658, 363021.0, 364387.0, 37840.62159378426, 63047.0, 63302.68348658319, 63619.36380636227, 63651.68174159192, 37600.81199593985, 39356.606433935296, 39370.803145984086, 39371.0, 39424.21151576952, 13981.300934953211, 14169.716605667829, 14172.149671019772, 14173.0, 14418.855811441961, 11393.886061139283, 11556.133290003283, 11617.302961822317, 11791.233569817934, 11805.586804461802, 9215.0, 9254.12085851843, 9261.536923153815, 9318.813623727623, 9324.0, 3532.293541291752, 3593.4789455528803, 3605.5853576839, 3615.078155070493, 3668.9633103669157, 1075.2419544221304, 1093.8031154392127, 1102.5589764094266, 1125.4654039331251, 1198.9160758746978, 34325.828370857984, 34394.0, 34506.0, 34591.65408345935, 34664.82667586646, 1187.5299377295205, 1208.0396085112357, 1252.1360261419773, 1267.5690265309838, 1287.2533930320392, 17788.199531021135, 17931.73102403465, 18082.0, 18151.364702235358, 18162.81837181638, 557034.0, 558074.0, 559232.0, 560979.0, 562294.0, 63871.6806415965, 64396.0, 64415.355846441875, 64427.67786161039, 64468.0, 1355.830521184852, 1403.1861520318396, 1445.8626430489296, 1459.9416023359158, 1484.85893840085, 30927.84536077305, 33647.32705345928, 33807.66192338045, 33986.32027359489, 34643.307133857685, 53324.73337633287, 53801.192982106084, 54045.0, 54055.459445405046, 54143.458565415414, 415454.0, 416228.0, 416278.0, 418059.0, 419173.0, 26766.866165669046, 30127.69872301249, 30651.386972260876, 30767.84616076995, 30982.0, 51681.7415912918, 57441.42558574361, 57964.420355795904, 58267.70866145642, 58805.41194587999, 44165.558344417426, 44374.0, 44710.0, 45330.76646849854, 45865.770671146434, 43954.780226098665, 44341.556584435035, 44540.77729611331, 44682.0, 45801.77099114483, 44023.339649905916, 44467.777661112785, 44980.0, 45092.54907450883, 45436.318455222536, 37099.62900370962, 39147.0, 40096.198076037734, 40124.598754013256, 40342.39486407647, 25739.485210296065, 25892.352691182117, 26047.479050418508, 26119.608205877306, 26371.0], "value": 20401.466886989707, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.69634211538744, 427.4037717384251, 427.8352834158849, 428.5757100470535, 443.22435737459443, 18313.084345782725, 19082.618347633055, 19660.311889083885, 19694.113764880578, 20040.599188016247, 18282.451526454195, 18416.355427560036, 18962.715559266588, 19035.428937131874, 19114.23543058276, 5683.886322273563, 5871.031279838821, 5903.911441328383, 5909.349971503131, 5915.290165180184, 4297.699161058727, 5214.035403450355, 5884.941150588504, 5944.256967879015, 6692.263850976413, 6726.260111387744, 6744.898826517606, 6755.425788807957, 6758.493113016543, 6761.797146085625, 6429.7106630201515, 6609.339066093352, 6669.23303820062, 6670.799876003728, 6671.799846004652, 3914.843406263746, 5419.214213938996, 5715.742791574389, 5895.764169433218, 5935.465808077251, 22001.339959801233, 22712.545749084926, 22860.0, 23115.53768924612, 23122.65316020261, 2473.2810696565894, 2495.5508008558454, 2514.2959971208093, 2731.1260396673074, 2843.3602439450956, 14101.78847317291, 14106.788398174036, 14127.71744565103, 14438.422463101464, 14607.70784584313, 1986.6225417170835, 2040.8265297449586, 2041.428400047974, 2046.1303945822965, 2075.1491888325863, 47778.28332575014, 63279.367206327806, 70971.2858926342, 79570.0, 84036.0, 10535.84196237057, 10915.126789856793, 10958.890411095947, 11049.226554141133, 11064.944675276573, 15053.096814191184, 15173.317200725995, 15180.240987950558, 15208.16355100458, 15212.391504339703, 7877.881831772528, 7886.408519361071, 7958.482698624571, 8141.837163256701, 8193.180681931879, 7600.315971562586, 7615.466917315845, 7659.731909383145, 7767.1067827200595, 7787.06555213378, 6553.442957348583, 6775.525713200029, 6855.143107111611, 6859.348361905611, 6895.517313788087, 1889.9811001889805, 1954.355062829263, 1958.392898201562, 1968.7539057617798, 1992.4520756791999, 999.8150342186573, 1019.4393083804026, 1051.164324362134, 1051.3008849115406, 1133.1784456269304, 18725.625487490175, 18849.151788169293, 19177.80822191788, 19393.32123375675, 19602.11790469432, 1050.0497050169456, 1069.256866477806, 1095.8684957805115, 1124.2018167101287, 1166.7083229192701, 10756.354618722746, 11338.376389298668, 11374.829377559343, 11394.25937314072, 11415.942920285346, 483286.0, 499283.0, 500458.0, 501746.0, 505496.0, 99657.0, 101889.0, 102655.48672256591, 102863.4856825726, 103394.0, 977.1498796047484, 1065.2543219746208, 1115.1301984452302, 1125.8480105185859, 1131.688785583971, 16516.091614960813, 16888.66222675515, 16893.66212675764, 16928.40750573724, 17140.742888856428, 39034.21931561296, 40141.59858401379, 40144.7992760046, 40404.191916160926, 42386.57613423826, 57746.711266445076, 58363.4163658358, 58445.0, 59367.1094933585, 59422.70288648529, 8675.30597552207, 8732.912670873464, 8844.425112367675, 8883.378163528749, 9148.130927561871, 8860.64557417696, 12217.450214740536, 12326.938365308117, 12360.072994525626, 12659.936700316439, 10233.948830256099, 10810.35137891729, 11022.944885275523, 11024.724381890517, 11122.332660040423, 10501.42242176657, 10586.735331616463, 10624.734381640521, 10637.9468102659, 10740.62407815754, 10790.352578845295, 10996.615118470816, 11014.614488492864, 11092.0, 11179.66461006171, 8510.74467765968, 8776.692815751418, 8888.333374996771, 8994.05562415937, 9090.36367454272, 8402.663893444194, 10526.684199474028, 10932.945335273273, 10964.287321324407, 11191.608293709682], "value": 9454.426336390705, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.717500087482, 1589.9824112567956, 1609.1310692226198, 1820.8179182081794, 1931.063434234397, 9086.409383390075, 9499.382540134886, 9511.239100871931, 9769.902300976988, 9775.560099795508, 9552.856707149398, 9747.51262436877, 9750.902490975088, 9754.561044752985, 9756.0, 2444.7555244475543, 2480.677511923456, 2482.6276058591175, 2483.956738169964, 2484.3913241255827, 2391.6532102845076, 2412.0834083048417, 2412.5657381671294, 2414.915477958272, 2416.8549887006748, 2421.927342179729, 2424.309071914505, 2432.8661923594195, 2433.2578563538204, 2455.8772061396858, 2335.4745182333922, 2341.5785158671433, 2342.3207269891627, 2354.717433907942, 2403.579373609629, 2317.3974766560646, 2354.7880690737743, 2356.081128359941, 2359.9528009439887, 2361.8346715729904, 8237.093919668832, 9480.478573678445, 9513.429194248367, 9628.892677342124, 9655.08276713711, 5442.292501974757, 5598.776048958037, 5598.916016259759, 5600.4959553639965, 5608.4391560844, 3454.4990976308295, 3478.565179352581, 3524.8766293179615, 3525.735569832247, 3636.9818150909077, 1125.7410795516996, 1169.9298042117416, 1194.808830587102, 1199.4182821331647, 1211.0674780419115, 14007.439702411892, 14051.85948140526, 14104.365303561364, 14121.082129661641, 14143.363548640336, 4753.33453316536, 4877.829275975395, 4890.804367825318, 4896.265560165933, 4965.180745177077, 11615.76768464626, 11642.70893227676, 11650.126240531994, 11731.0, 11769.352685602204, 5618.943810561924, 5625.915611265835, 5628.662280263197, 5632.464915832989, 5633.746481408347, 4979.053979743837, 5060.5698515625845, 5166.250893620404, 5172.586193104543, 5195.402528709171, 3908.101136738566, 3961.6830653547645, 3984.5816189300285, 4014.397840323975, 4046.251443482965, 1208.697825543614, 1215.7629262293767, 1219.7682440336307, 1219.9024078073733, 1220.8046712526132, 1001.3491230700104, 1005.1657124586576, 1010.5099026971913, 1020.8366661334151, 1025.8563801067855, 6717.429018533382, 6726.697298621476, 6746.460283177334, 6752.966235168793, 6753.054572359873, 436.44135506551663, 453.8184726109583, 457.922153233951, 464.7583256706561, 468.44021394434003, 4432.268675668479, 4485.0, 4493.528179541101, 4529.796159172845, 4537.410136682276, 11946.0, 12349.259044457362, 12351.444185011516, 12366.876331236752, 12395.256284622952, 11737.35444550558, 12148.5140594377, 12155.149139560146, 12274.509019639114, 12486.313252771184, 9084.0, 9186.356955013085, 9203.585838637277, 9210.447373157633, 9218.44689318643, 6616.603003819786, 6814.420774234246, 6874.793756187322, 6937.340952609494, 7014.053102831256, 11203.663890083311, 11235.494402751732, 11419.942900285445, 11483.081353491534, 11485.425728713364, 11526.481308340977, 11705.122115841175, 11720.765584688428, 11727.413629318671, 11776.882231177578, 8531.146885311418, 8539.573021348784, 8560.334468752782, 8645.913540864762, 8662.480251184948, 9226.031266716898, 9248.815023699623, 9251.0, 9282.62869485213, 9322.906770932204, 8490.23587877094, 8514.276286515467, 8522.36082293818, 8620.52587107715, 8626.094260102598, 8089.9191008089165, 8188.058373287148, 8268.049174344787, 8292.419530632798, 8297.336213102817, 7421.740239091606, 7425.368843648351, 7449.366803821736, 7450.776476705708, 7453.850922981618, 5503.20203570472, 5593.580481463825, 5670.262865827416, 5751.166080918327, 5754.942450575608, 5627.859303517281, 5672.177534257592, 5677.716114194356, 5730.770769169353, 5876.265466816648], "value": 4901.768304506644, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.94628913084773, 322.79986408426777, 322.96285927118373, 332.95172200031, 340.6916740349984, 5298.417174110845, 5466.289382380295, 5469.425710300421, 5525.723713814314, 5535.69911070898, 5325.600579956509, 5402.163264490073, 5408.567314614831, 5442.292501974738, 5458.61789674723, 4234.136979728924, 4251.0, 4255.468066491689, 4258.765767882765, 4269.551697071809, 3998.4602078719436, 4087.713860029799, 4226.4716910386205, 4228.429162063113, 4258.510271318807, 4166.54171040135, 4180.5610410906875, 4186.74879507229, 4193.2242535130945, 4201.096764195698, 4181.644560212386, 4193.811278492475, 4195.160967806421, 4213.747175169499, 4217.873463796091, 3637.5453068366455, 3698.8335524901177, 3713.3687273163628, 3721.6836568891677, 3742.158014446755, 5193.454687257859, 5203.635745497817, 5230.764615592308, 5245.554127899133, 5261.210818377236, 4814.7351895645725, 4847.854564363075, 4849.975750121227, 4878.902072108992, 4885.193942999401, 5437.945620543823, 5674.432556744293, 5699.401562835884, 5699.68651724155, 5718.42815718429, 4068.2270368629866, 4088.9386659200136, 4091.977005748563, 4098.344264917629, 4102.364133559273, 5844.357120716761, 5886.440788125121, 5913.408659134096, 5988.892054969844, 5993.932093427804, 4077.571854955187, 4246.639035682002, 4268.9786551067045, 4273.594008569181, 4286.764227967429, 4298.591633794785, 4306.956930430656, 4312.813976156582, 4314.784260786948, 4324.200022995756, 4868.318435419044, 4873.780679869415, 4887.902241955141, 4891.730954797449, 4907.533784290486, 5624.943750562442, 5726.627769194989, 5771.451712087345, 5776.480116789509, 5782.2194003808945, 1396.273937552467, 1443.1990245413685, 1478.519481168603, 1485.695432436348, 1491.135141617859, 944.4003058058182, 950.5294879034784, 964.6671898195111, 966.0966995858895, 977.9145148884733, 4935.851924442272, 4952.390473096259, 4974.502549744997, 4975.676581022222, 5025.969676216367, 5176.404713457925, 5182.0, 5205.583553315726, 5224.831694846672, 5283.603729720209, 4696.553827386393, 5003.424606170337, 5056.696598204046, 5073.923891141636, 5075.898482030412, 2737.658547311803, 2774.5838124281117, 2802.2574017885277, 2811.6063751074867, 2814.1426658405353, 5354.384245811703, 5394.811181608703, 5400.83797486076, 5408.080131553242, 5437.619366644297, 5081.28861959326, 5265.631405801557, 5276.973615131977, 5304.628675992721, 5312.654677445954, 5349.0, 5401.864953376197, 5410.0, 5418.187271909166, 5430.262316058824, 5194.2858856578, 5194.636375453682, 5245.527902488641, 5253.0, 5257.237700533477, 4810.103815908985, 5122.513361230677, 5154.871128221825, 5168.5528052145455, 5178.870528236674, 5399.568034557305, 5403.729813509387, 5405.972970135282, 5408.349908544863, 5422.9186562202385, 3867.1878905430176, 3877.3990031545154, 3883.165119499321, 3888.9416658750706, 3897.006263402814, 4867.90264194707, 5198.2202669599865, 5256.6583172095225, 5643.657881978933, 5754.223179870663, 5717.714114294352, 5724.255846740064, 5725.914111288251, 5726.455986681259, 5736.397678243724, 5694.231278777478, 5704.0, 5752.9712351437975, 5784.392638772868, 5798.362180160095, 5511.448855114457, 5655.0, 5661.150827375926, 5696.8006119785605, 5699.629524080921, 5735.397783232867, 5785.161151632906, 5786.42135786418, 5794.681292528951, 5841.649501029951, 5467.343918729778, 5688.317401911797, 5699.914501282401, 5705.630648644213, 5720.141978703228], "value": 4201.817312540981, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.1511991449661, 760.2397602397599, 804.3524962405262, 817.7546735979208, 821.1419067074906, 14376.49682261121, 14413.927930360334, 14420.783688244685, 14440.638984025418, 14593.0, 14002.649933751662, 14064.859351406483, 14073.507427240049, 14084.436622535111, 14085.647858803535, 692.4875592061888, 823.4318320358963, 845.5349557743248, 861.1216559109704, 865.4547634989979, 862.4006315610653, 868.5179725252482, 871.4074429388014, 871.8735783311416, 876.0713643537838, 909.0137201136774, 910.9590068446903, 914.5838643417262, 915.9313051521129, 917.6788124156525, 723.2478222648439, 868.9522076285801, 902.201551626815, 903.6566104880136, 905.4612505559181, 670.8222321084917, 674.834665506949, 674.9358810912955, 675.5710124071201, 675.5845155229548, 13341.86658133416, 13402.396892139877, 13408.061435699505, 13410.798838017437, 13438.193708377528, 14300.141991480441, 14646.41414343425, 15050.548483545512, 15084.849151508453, 15229.086254824635, 12324.753504929851, 12612.558560450432, 13128.0, 13226.0, 13270.734585308337, 4399.186150562156, 4439.578240067189, 4444.644428445717, 4457.554244575549, 4516.932246016312, 74410.62794686046, 74724.0, 75781.62109189473, 77864.2213577857, 78500.60749696272, 11373.94313028446, 11495.597654082067, 11641.47613357401, 11658.41707914601, 11924.88075119255, 23132.7686723134, 23300.417489562897, 23361.41596460068, 23397.53204935926, 23437.0, 8239.134890836496, 8354.624041918129, 8357.95821020891, 8701.17338852808, 8808.471491710518, 4706.388169537938, 4779.928301075487, 4836.250381190921, 4907.288443175719, 4961.330220420197, 3707.351213537674, 3788.109794198361, 3792.146766977408, 3794.734368594172, 3799.4490798834013, 579.2990481517411, 606.3148642034473, 609.1289456077851, 622.327885883245, 700.1912790726617, 669.7923643670478, 710.0, 794.2137284088826, 809.0938149272852, 809.6599428240153, 9556.139947404628, 9744.464054477072, 9905.752356191153, 10076.0, 10093.0, 656.2879275985609, 661.4840424468922, 661.751843058853, 666.7133132752866, 691.1602403080282, 4709.95290047102, 4725.503822098699, 4738.0, 4793.065352256276, 4839.056384005085, 77591.61204193943, 124146.0, 125587.3720631391, 127520.36239818741, 128511.35744321406, 66563.66718166474, 67764.0, 79538.0, 104070.47964760127, 106919.46540267249, 93064.53467732618, 94747.52626236825, 94903.52548237077, 96733.0, 98542.0, 9146.359754817098, 9195.816083678423, 9304.395214311047, 9305.02297258805, 9310.627574896927, 43003.784981074896, 46734.53265467438, 57525.424745753684, 57563.71218143882, 58068.0, 48973.0, 70093.64953175202, 71557.28442715506, 73724.0, 73819.26180738339, 12254.326012069065, 12349.19730217533, 12379.69050773738, 12779.61661150167, 13000.414981326052, 14271.500497482539, 14482.275886205436, 14701.779473308123, 14760.48338308154, 14985.550433487013, 13243.271620060605, 13710.0, 13788.93105534506, 13871.514496992557, 13889.305534723426, 13106.868931310946, 13163.73672526525, 13298.135621184592, 13435.328233588087, 13638.795418068936, 13301.600951971457, 13548.525801596898, 13904.165750055028, 14095.224762638154, 14185.36165872559, 10918.290311129751, 11289.153313501685, 11304.321740695585, 11436.885631143583, 11465.828012579985, 11397.829032564685, 11546.76906461883, 11570.0, 11693.824592631288, 11891.28652280866], "value": 7644.4295999520655, "unit": "score"}], "name": "dromaeo_css", "value": 7400.101399817342, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 110.0, 110.0, 111.0, 110.0, 112.0, 112.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 123.0, 177.0, 124.0, 123.0, 158.0, 124.0, 170.0, 129.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 100.0, 76.0, 75.0, 75.0, 75.0, 107.0, 74.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 113.0, 84.0, 84.0, 84.0, 116.0, 85.0, 84.0, 125.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 144.0, 145.0, 144.0, 145.0, 145.0, 145.0, 144.0], "value": 144.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 54.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 60.0, 60.0, 59.0, 61.0, 59.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 82.0, 81.0, 80.0, 77.0, 77.0, 110.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 117.0, 115.0, 124.0, 125.0, 117.0, 117.0, 119.0, 140.0, 128.0], "value": 122.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 153.0, 153.0, 156.0, 156.0, 167.0, 160.0, 155.0, 154.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 66.0, 62.0, 63.0, 63.0, 61.0, 65.0, 62.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1478.2, "alertThreshold": 2.0}]} 16:51:06 INFO - Return code: 0 16:51:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:51:06 ERROR - # TBPL SUCCESS # 16:51:06 INFO - Running post-action listener: _resource_record_post_action 16:51:06 INFO - ##### 16:51:06 INFO - ##### Finished run-tests step (success) 16:51:06 INFO - ##### 16:51:06 INFO - Running post-run listener: _resource_record_post_run 16:51:06 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 368640; Write bytes: 455077888; Read time: 88; Write time: 360784 16:51:06 INFO - TinderboxPrint: CPU usage
13.2% 16:51:06 INFO - TinderboxPrint: I/O read bytes / time
368,640 / 88 16:51:06 INFO - TinderboxPrint: I/O write bytes / time
455,077,888 / 360,784 16:51:06 INFO - TinderboxPrint: CPU idle
8,228.4 (86.8%) 16:51:06 INFO - TinderboxPrint: CPU user
1,202.3 (12.7%) 16:51:06 INFO - TinderboxPrint: Swap in / out
0 / 0 16:51:06 INFO - install - Wall time: 13s; CPU: 14.0%; Read bytes: 0; Write bytes: 235094016; Read time: 0; Write time: 221412 16:51:06 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 344064; Write bytes: 213372928; Read time: 80; Write time: 137028 16:51:07 INFO - Running post-run listener: _upload_blobber_files 16:51:07 INFO - Blob upload gear active. 16:51:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:51:07 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 16:51:07 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:51:07 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:51:07 INFO - (blobuploader) - INFO - Open directory for files ... 16:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:08 INFO - (blobuploader) - INFO - Done attempting. 16:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:12 INFO - (blobuploader) - INFO - Done attempting. 16:51:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:51:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:13 INFO - (blobuploader) - INFO - Done attempting. 16:51:13 INFO - (blobuploader) - INFO - Iteration through files over. 16:51:13 INFO - Return code: 0 16:51:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:51:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:51:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/735aa042826a7df5e259701dc84037c9c57e6b34edc709364e9e0effb1237fd00c44936e06e130ed7e39a682a24acdfc444919448bd262d39d925471f56e740d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88b47254a58374d0d64ad69bc805245c72913aa3f3d59ad3bd454ed3aceca6d8c1460c6a7eaecd6b5d4de3bd593015d9313e7d49bd302ef75a5d4df56c99293a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1494f290a030177f881a39ea72b50929d6d879e0b9cd4bd937596d569145fc9f51ee1e84efb8dbd4748acbd66215eedc82b5bb203590dc7700f42d0b7ee51b65"} 16:51:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:51:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:51:13 INFO - Contents: 16:51:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/735aa042826a7df5e259701dc84037c9c57e6b34edc709364e9e0effb1237fd00c44936e06e130ed7e39a682a24acdfc444919448bd262d39d925471f56e740d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88b47254a58374d0d64ad69bc805245c72913aa3f3d59ad3bd454ed3aceca6d8c1460c6a7eaecd6b5d4de3bd593015d9313e7d49bd302ef75a5d4df56c99293a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1494f290a030177f881a39ea72b50929d6d879e0b9cd4bd937596d569145fc9f51ee1e84efb8dbd4748acbd66215eedc82b5bb203590dc7700f42d0b7ee51b65"} 16:51:13 INFO - Running post-run listener: copy_logs_to_upload_dir 16:51:13 INFO - Copying logs to upload dir... 16:51:13 INFO - mkdir: /builds/slave/test/build/upload/logs 16:51:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.533411 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-01 16:51:13.520887) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 16:51:13.524346) ========= 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=eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338 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-Beta/sha512/735aa042826a7df5e259701dc84037c9c57e6b34edc709364e9e0effb1237fd00c44936e06e130ed7e39a682a24acdfc444919448bd262d39d925471f56e740d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88b47254a58374d0d64ad69bc805245c72913aa3f3d59ad3bd454ed3aceca6d8c1460c6a7eaecd6b5d4de3bd593015d9313e7d49bd302ef75a5d4df56c99293a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1494f290a030177f881a39ea72b50929d6d879e0b9cd4bd937596d569145fc9f51ee1e84efb8dbd4748acbd66215eedc82b5bb203590dc7700f42d0b7ee51b65"} build_url:https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/735aa042826a7df5e259701dc84037c9c57e6b34edc709364e9e0effb1237fd00c44936e06e130ed7e39a682a24acdfc444919448bd262d39d925471f56e740d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88b47254a58374d0d64ad69bc805245c72913aa3f3d59ad3bd454ed3aceca6d8c1460c6a7eaecd6b5d4de3bd593015d9313e7d49bd302ef75a5d4df56c99293a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1494f290a030177f881a39ea72b50929d6d879e0b9cd4bd937596d569145fc9f51ee1e84efb8dbd4748acbd66215eedc82b5bb203590dc7700f42d0b7ee51b65"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 16:51:13.549463) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:51:13.549783) ========= 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=eca2ab57546de7b41f256b19000001ef-1470094242.611758-599326338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:51:13.632734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 16:51:13.633069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 16:51:13.633361) ========= ========= Total master_lag: 0.16 =========