builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-026 starttime: 1470048029.64 results: success (0) buildid: 20160731234823 builduid: ac593188bfc9435eb9125393ed1fd078 revision: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.640776) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.641276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.641671) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.762492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.762791) ========= 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=41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 03:40:30-- 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% 66.6M=0s 2016-08-01 03:40:30 (66.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119948 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.916997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.917313) ========= 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=41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020511 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.984237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 03:40:29.984575) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff --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=41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 03:40:31,081 truncating revision to first 12 chars 2016-08-01 03:40:31,081 Setting DEBUG logging. 2016-08-01 03:40:31,081 attempt 1/10 2016-08-01 03:40:31,081 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcdf4bb70356?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 03:40:32,142 unpacking tar archive at: mozilla-aurora-fcdf4bb70356/testing/mozharness/ program finished with exit code 0 elapsedTime=1.425382 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 03:40:31.432735) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.433121) ========= script_repo_revision: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.433570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.433879) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.451006) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-08-01 03:40:31.451311) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:40:32 INFO - MultiFileLogger online at 20160801 03:40:32 in /builds/slave/test 03:40:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:40:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:40:32 INFO - {'append_to_log': False, 03:40:32 INFO - 'base_work_dir': '/builds/slave/test', 03:40:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:40:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:40:32 INFO - 'branch': 'Mozilla-Aurora', 03:40:32 INFO - 'buildbot_json_path': 'buildprops.json', 03:40:32 INFO - 'config_files': ('talos/linux_config.py',), 03:40:32 INFO - 'default_actions': ('clobber', 03:40:32 INFO - 'read-buildbot-config', 03:40:32 INFO - 'download-and-extract', 03:40:32 INFO - 'populate-webroot', 03:40:32 INFO - 'create-virtualenv', 03:40:32 INFO - 'install', 03:40:32 INFO - 'run-tests'), 03:40:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:40:32 INFO - 'download_minidump_stackwalk': True, 03:40:32 INFO - 'download_symbols': 'ondemand', 03:40:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:40:32 INFO - 'tooltool.py': '/tools/tooltool.py', 03:40:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:40:32 INFO - '/tools/misc-python/virtualenv.py')}, 03:40:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:40:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:40:32 INFO - 'installer_path': 'installer.exe', 03:40:32 INFO - 'log_level': 'info', 03:40:32 INFO - 'log_name': 'talos', 03:40:32 INFO - 'log_to_console': True, 03:40:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:40:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:40:32 INFO - 'opt_config_files': (), 03:40:32 INFO - 'pip_index': False, 03:40:32 INFO - 'sps_profile': False, 03:40:32 INFO - 'sps_profile_interval': 0, 03:40:32 INFO - 'suite': 'dromaeojs', 03:40:32 INFO - 'system_bits': '32', 03:40:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:40:32 INFO - 'title': 'talos-linux64-ix-026', 03:40:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:40:32 INFO - 'use_talos_json': True, 03:40:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:40:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:40:32 INFO - 'work_dir': 'build'} 03:40:32 INFO - ##### 03:40:32 INFO - ##### Running clobber step. 03:40:32 INFO - ##### 03:40:32 INFO - Running pre-action listener: _resource_record_pre_action 03:40:32 INFO - Running main action method: clobber 03:40:32 INFO - rmtree: /builds/slave/test/build 03:40:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:40:33 INFO - Running post-action listener: _resource_record_post_action 03:40:33 INFO - ##### 03:40:33 INFO - ##### Finished clobber step (success) 03:40:33 INFO - ##### 03:40:33 INFO - ##### 03:40:33 INFO - ##### Running read-buildbot-config step. 03:40:33 INFO - ##### 03:40:33 INFO - Running pre-action listener: _resource_record_pre_action 03:40:33 INFO - Running main action method: read_buildbot_config 03:40:33 INFO - Using buildbot properties: 03:40:33 INFO - { 03:40:33 INFO - "project": "", 03:40:33 INFO - "product": "firefox", 03:40:33 INFO - "script_repo_revision": "production", 03:40:33 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:40:33 INFO - "repository": "", 03:40:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:40:33 INFO - "buildid": "20160731234823", 03:40:33 INFO - "pgo_build": "True", 03:40:33 INFO - "basedir": "/builds/slave/test", 03:40:33 INFO - "buildnumber": 441, 03:40:33 INFO - "slavename": "talos-linux64-ix-026", 03:40:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:40:33 INFO - "platform": "ubuntu64_hw", 03:40:33 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:40:33 INFO - "branch": "mozilla-aurora", 03:40:33 INFO - "repo_path": "releases/mozilla-aurora", 03:40:33 INFO - "revision": "fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff", 03:40:33 INFO - "stage_platform": "linux64-pgo", 03:40:33 INFO - "builduid": "ac593188bfc9435eb9125393ed1fd078", 03:40:33 INFO - "slavebuilddir": "test" 03:40:33 INFO - } 03:40:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:40:33 INFO - Running post-action listener: _resource_record_post_action 03:40:33 INFO - ##### 03:40:33 INFO - ##### Finished read-buildbot-config step (success) 03:40:33 INFO - ##### 03:40:33 INFO - ##### 03:40:33 INFO - ##### Running download-and-extract step. 03:40:33 INFO - ##### 03:40:33 INFO - Running pre-action listener: _resource_record_pre_action 03:40:33 INFO - Running main action method: download_and_extract 03:40:33 INFO - mkdir: /builds/slave/test/build/tests 03:40:33 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')]} 03:40:33 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:40:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:40:38 INFO - Downloaded 1361 bytes. 03:40:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:38 INFO - Using the following test package requirements: 03:40:38 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:40:38 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:40:38 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:40:38 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'jsshell-linux-x86_64.zip'], 03:40:38 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:40:38 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:40:38 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:40:38 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:40:38 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:40:38 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:38 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:40:38 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:40:38 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')]} 03:40:38 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:40:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:40:58 INFO - Downloaded 21967710 bytes. 03:40:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:41:00 INFO - Return code: 0 03:41:00 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:00 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:41:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:41:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:41:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:41:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:41:03 INFO - Downloaded 21967710 bytes. 03:41:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:41:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:41:05 INFO - Return code: 0 03:41:05 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')]} 03:41:05 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:41:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:41:06 INFO - Downloaded 10833369 bytes. 03:41:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:41:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:41:06 INFO - Return code: 0 03:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:06 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:41:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:41:08 INFO - Downloaded 61985118 bytes. 03:41:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:08 INFO - mkdir: /builds/slave/test/properties 03:41:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:41:08 INFO - Writing to file /builds/slave/test/properties/build_url 03:41:08 INFO - Contents: 03:41:08 INFO - build_url:https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:08 INFO - Running post-action listener: _resource_record_post_action 03:41:08 INFO - Running post-action listener: set_extra_try_arguments 03:41:08 INFO - ##### 03:41:08 INFO - ##### Finished download-and-extract step (success) 03:41:08 INFO - ##### 03:41:08 INFO - ##### 03:41:08 INFO - ##### Running populate-webroot step. 03:41:08 INFO - ##### 03:41:08 INFO - Running pre-action listener: _resource_record_pre_action 03:41:08 INFO - Running main action method: populate_webroot 03:41:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:41:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:41:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:41:08 INFO - '--tppagecycles', 03:41:08 INFO - '7'], 03:41:08 INFO - 'tests': ['tsvgm']}}, 03:41:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:41:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:41:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:41:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:41:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['damp', 'tps']}, 03:41:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'tests': ['damp', 'tps']}, 03:41:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['dromaeo_dom']}, 03:41:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:41:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other-e10s': {'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tsvgx', 03:41:08 INFO - 'tsvgr_opacity', 03:41:08 INFO - 'tart', 03:41:08 INFO - 'tscrollx', 03:41:08 INFO - 'cart']}, 03:41:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:41:08 INFO - 'tsvgr_opacity', 03:41:08 INFO - 'tart', 03:41:08 INFO - 'tscrollx', 03:41:08 INFO - 'cart']}, 03:41:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tp5o']}, 03:41:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'tests': ['tp5o']}, 03:41:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s', 03:41:08 INFO - '--xperf_path', 03:41:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:08 INFO - 'tests': ['tp5n']}, 03:41:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--xperf_path', 03:41:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:08 INFO - 'tests': ['tp5n']}}, 03:41:08 INFO - 'talos.zip': {'path': '', 03:41:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:41:08 INFO - Running post-action listener: _resource_record_post_action 03:41:08 INFO - ##### 03:41:08 INFO - ##### Finished populate-webroot step (success) 03:41:08 INFO - ##### 03:41:08 INFO - ##### 03:41:08 INFO - ##### Running create-virtualenv step. 03:41:08 INFO - ##### 03:41:08 INFO - Running pre-action listener: _resource_record_pre_action 03:41:08 INFO - Running main action method: create_virtualenv 03:41:08 INFO - Creating virtualenv /builds/slave/test/build/venv 03:41:08 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 03:41:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:41:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:41:08 INFO - Using real prefix '/usr' 03:41:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:41:09 INFO - Installing distribute.............................................................................................................................................................................................done. 03:41:11 INFO - Installing pip.................done. 03:41:11 INFO - Return code: 0 03:41:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:41:11 INFO - Using env: {'DISPLAY': ':0', 03:41:11 INFO - 'HOME': '/home/cltbld', 03:41:11 INFO - 'LANG': 'en_US.UTF-8', 03:41:11 INFO - 'LANGUAGE': 'en_US:en', 03:41:11 INFO - 'LOGNAME': 'cltbld', 03:41:11 INFO - 'MAIL': '/var/mail/cltbld', 03:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:11 INFO - 'MOZ_NO_REMOTE': '1', 03:41:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:11 INFO - 'NO_EM_RESTART': '1', 03:41:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:11 INFO - 'PWD': '/builds/slave/test', 03:41:11 INFO - 'SHELL': '/bin/bash', 03:41:11 INFO - 'SHLVL': '1', 03:41:11 INFO - 'TERM': 'linux', 03:41:11 INFO - 'TMOUT': '86400', 03:41:11 INFO - 'USER': 'cltbld', 03:41:11 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:11 INFO - '_': '/tools/buildbot/bin/python'} 03:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:11 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 03:41:11 INFO - Cleaning up... 03:41:11 INFO - Return code: 0 03:41:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:41:11 INFO - Using env: {'DISPLAY': ':0', 03:41:11 INFO - 'HOME': '/home/cltbld', 03:41:11 INFO - 'LANG': 'en_US.UTF-8', 03:41:11 INFO - 'LANGUAGE': 'en_US:en', 03:41:11 INFO - 'LOGNAME': 'cltbld', 03:41:11 INFO - 'MAIL': '/var/mail/cltbld', 03:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:11 INFO - 'MOZ_NO_REMOTE': '1', 03:41:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:11 INFO - 'NO_EM_RESTART': '1', 03:41:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:11 INFO - 'PWD': '/builds/slave/test', 03:41:11 INFO - 'SHELL': '/bin/bash', 03:41:11 INFO - 'SHLVL': '1', 03:41:11 INFO - 'TERM': 'linux', 03:41:11 INFO - 'TMOUT': '86400', 03:41:11 INFO - 'USER': 'cltbld', 03:41:11 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:11 INFO - '_': '/tools/buildbot/bin/python'} 03:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:11 INFO - Downloading/unpacking psutil>=3.1.1 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:41:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:13 INFO - Installing collected packages: psutil 03:41:13 INFO - Running setup.py install for psutil 03:41:13 INFO - building 'psutil._psutil_linux' extension 03:41:13 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 03:41:14 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 03:41:14 INFO - building 'psutil._psutil_posix' extension 03:41:14 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 03:41:14 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 03:41:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:14 INFO - Successfully installed psutil 03:41:14 INFO - Cleaning up... 03:41:14 INFO - Return code: 0 03:41:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:41:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:41:14 INFO - Using env: {'DISPLAY': ':0', 03:41:14 INFO - 'HOME': '/home/cltbld', 03:41:14 INFO - 'LANG': 'en_US.UTF-8', 03:41:14 INFO - 'LANGUAGE': 'en_US:en', 03:41:14 INFO - 'LOGNAME': 'cltbld', 03:41:14 INFO - 'MAIL': '/var/mail/cltbld', 03:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:14 INFO - 'MOZ_NO_REMOTE': '1', 03:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:14 INFO - 'NO_EM_RESTART': '1', 03:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:14 INFO - 'PWD': '/builds/slave/test', 03:41:14 INFO - 'SHELL': '/bin/bash', 03:41:14 INFO - 'SHLVL': '1', 03:41:14 INFO - 'TERM': 'linux', 03:41:14 INFO - 'TMOUT': '86400', 03:41:14 INFO - 'USER': 'cltbld', 03:41:14 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:14 INFO - '_': '/tools/buildbot/bin/python'} 03:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:41:16 INFO - Installing collected packages: mozsystemmonitor 03:41:16 INFO - Running setup.py install for mozsystemmonitor 03:41:16 INFO - Successfully installed mozsystemmonitor 03:41:16 INFO - Cleaning up... 03:41:16 INFO - Return code: 0 03:41:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:16 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:16 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 03:41:16 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 03:41:16 INFO - Using env: {'DISPLAY': ':0', 03:41:16 INFO - 'HOME': '/home/cltbld', 03:41:16 INFO - 'LANG': 'en_US.UTF-8', 03:41:16 INFO - 'LANGUAGE': 'en_US:en', 03:41:16 INFO - 'LOGNAME': 'cltbld', 03:41:16 INFO - 'MAIL': '/var/mail/cltbld', 03:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:16 INFO - 'MOZ_NO_REMOTE': '1', 03:41:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:16 INFO - 'NO_EM_RESTART': '1', 03:41:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:16 INFO - 'PWD': '/builds/slave/test', 03:41:16 INFO - 'SHELL': '/bin/bash', 03:41:16 INFO - 'SHLVL': '1', 03:41:16 INFO - 'TERM': 'linux', 03:41:16 INFO - 'TMOUT': '86400', 03:41:16 INFO - 'USER': 'cltbld', 03:41:16 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:16 INFO - '_': '/tools/buildbot/bin/python'} 03:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:16 INFO - Downloading/unpacking jsonschema==2.5.1 03:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:41:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41:18 INFO - Downloading functools32-3.2.3-2.tar.gz 03:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:41:18 INFO - warning: no files found matching '*.txt' 03:41:18 INFO - no previously-included directories found matching 'build' 03:41:18 INFO - no previously-included directories found matching 'dist' 03:41:18 INFO - no previously-included directories found matching '.git*' 03:41:18 INFO - Installing collected packages: jsonschema, functools32 03:41:19 INFO - Running setup.py install for functools32 03:41:19 INFO - warning: no files found matching '*.txt' 03:41:19 INFO - no previously-included directories found matching 'build' 03:41:19 INFO - no previously-included directories found matching 'dist' 03:41:19 INFO - no previously-included directories found matching '.git*' 03:41:19 INFO - Successfully installed jsonschema functools32 03:41:19 INFO - Cleaning up... 03:41:19 INFO - Return code: 0 03:41:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:41:19 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')]} 03:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:19 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')]} 03:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:19 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:19 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 03:41:19 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 03:41:19 INFO - Using env: {'DISPLAY': ':0', 03:41:19 INFO - 'HOME': '/home/cltbld', 03:41:19 INFO - 'LANG': 'en_US.UTF-8', 03:41:19 INFO - 'LANGUAGE': 'en_US:en', 03:41:19 INFO - 'LOGNAME': 'cltbld', 03:41:19 INFO - 'MAIL': '/var/mail/cltbld', 03:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:19 INFO - 'MOZ_NO_REMOTE': '1', 03:41:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:19 INFO - 'NO_EM_RESTART': '1', 03:41:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:19 INFO - 'PWD': '/builds/slave/test', 03:41:19 INFO - 'SHELL': '/bin/bash', 03:41:19 INFO - 'SHLVL': '1', 03:41:19 INFO - 'TERM': 'linux', 03:41:19 INFO - 'TMOUT': '86400', 03:41:19 INFO - 'USER': 'cltbld', 03:41:19 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:19 INFO - '_': '/tools/buildbot/bin/python'} 03:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:19 INFO - Downloading/unpacking blobuploader==1.2.4 03:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - Downloading blobuploader-1.2.4.tar.gz 03:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:41:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:41:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 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 03:41:21 INFO - Downloading docopt-0.6.1.tar.gz 03:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:41:22 INFO - Installing collected packages: blobuploader, requests, docopt 03:41:22 INFO - Running setup.py install for blobuploader 03:41:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:41:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:41:22 INFO - Running setup.py install for requests 03:41:22 INFO - Running setup.py install for docopt 03:41:22 INFO - Successfully installed blobuploader requests docopt 03:41:22 INFO - Cleaning up... 03:41:22 INFO - Return code: 0 03:41:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:22 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')]} 03:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:22 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')]} 03:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:22 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:22 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 03:41:22 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 03:41:22 INFO - Using env: {'DISPLAY': ':0', 03:41:22 INFO - 'HOME': '/home/cltbld', 03:41:22 INFO - 'LANG': 'en_US.UTF-8', 03:41:22 INFO - 'LANGUAGE': 'en_US:en', 03:41:22 INFO - 'LOGNAME': 'cltbld', 03:41:22 INFO - 'MAIL': '/var/mail/cltbld', 03:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:22 INFO - 'MOZ_NO_REMOTE': '1', 03:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:22 INFO - 'NO_EM_RESTART': '1', 03:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:22 INFO - 'PWD': '/builds/slave/test', 03:41:22 INFO - 'SHELL': '/bin/bash', 03:41:22 INFO - 'SHLVL': '1', 03:41:22 INFO - 'TERM': 'linux', 03:41:22 INFO - 'TMOUT': '86400', 03:41:22 INFO - 'USER': 'cltbld', 03:41:22 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:22 INFO - '_': '/tools/buildbot/bin/python'} 03:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:22 INFO - Running setup.py (path:/tmp/pip-Y8hj_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:22 INFO - Running setup.py (path:/tmp/pip-A4JVgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:22 INFO - Running setup.py (path:/tmp/pip-YTftYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:23 INFO - Running setup.py (path:/tmp/pip-ITTLmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:23 INFO - Running setup.py (path:/tmp/pip-_QAnmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:23 INFO - Running setup.py (path:/tmp/pip-KjKncO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:23 INFO - Running setup.py (path:/tmp/pip-Vhu1xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:23 INFO - Running setup.py (path:/tmp/pip-_eqOBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:23 INFO - Running setup.py (path:/tmp/pip-HGSO4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:23 INFO - Running setup.py (path:/tmp/pip-92BHZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:23 INFO - Running setup.py (path:/tmp/pip-74cJGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:23 INFO - Running setup.py (path:/tmp/pip-yhDraS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:23 INFO - Running setup.py (path:/tmp/pip-uCK5ZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:23 INFO - Running setup.py (path:/tmp/pip-G8PTTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:23 INFO - Running setup.py (path:/tmp/pip-fo2Nsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:23 INFO - Running setup.py (path:/tmp/pip-zcoFVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:24 INFO - Running setup.py (path:/tmp/pip-UQbEk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:41:24 INFO - Running setup.py install for manifestparser 03:41:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozcrash 03:41:24 INFO - Running setup.py install for mozdebug 03:41:24 INFO - Running setup.py install for mozdevice 03:41:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozfile 03:41:24 INFO - Running setup.py install for mozhttpd 03:41:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozinfo 03:41:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozInstall 03:41:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozleak 03:41:24 INFO - Running setup.py install for mozlog 03:41:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for moznetwork 03:41:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for mozprocess 03:41:25 INFO - Running setup.py install for mozprofile 03:41:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for mozrunner 03:41:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for mozscreenshot 03:41:25 INFO - Running setup.py install for moztest 03:41:25 INFO - Running setup.py install for mozversion 03:41:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:41:25 INFO - Cleaning up... 03:41:25 INFO - Return code: 0 03:41:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:25 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:25 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 03:41:25 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 03:41:25 INFO - Using env: {'DISPLAY': ':0', 03:41:25 INFO - 'HOME': '/home/cltbld', 03:41:25 INFO - 'LANG': 'en_US.UTF-8', 03:41:25 INFO - 'LANGUAGE': 'en_US:en', 03:41:25 INFO - 'LOGNAME': 'cltbld', 03:41:25 INFO - 'MAIL': '/var/mail/cltbld', 03:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:25 INFO - 'MOZ_NO_REMOTE': '1', 03:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:25 INFO - 'NO_EM_RESTART': '1', 03:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:25 INFO - 'PWD': '/builds/slave/test', 03:41:25 INFO - 'SHELL': '/bin/bash', 03:41:25 INFO - 'SHLVL': '1', 03:41:25 INFO - 'TERM': 'linux', 03:41:25 INFO - 'TMOUT': '86400', 03:41:25 INFO - 'USER': 'cltbld', 03:41:25 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:25 INFO - '_': '/tools/buildbot/bin/python'} 03:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:26 INFO - Running setup.py (path:/tmp/pip-I8AVgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:26 INFO - Running setup.py (path:/tmp/pip-P7cUS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:26 INFO - Running setup.py (path:/tmp/pip-vfPuql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:26 INFO - Running setup.py (path:/tmp/pip-SbvKuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:26 INFO - Running setup.py (path:/tmp/pip-ezjA0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:26 INFO - Running setup.py (path:/tmp/pip-alRJbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:26 INFO - Running setup.py (path:/tmp/pip-roLEwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:26 INFO - Running setup.py (path:/tmp/pip-HlIpEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:26 INFO - Running setup.py (path:/tmp/pip-0Nmg1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:26 INFO - Running setup.py (path:/tmp/pip-u6TSot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:26 INFO - Running setup.py (path:/tmp/pip-lgUZBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:26 INFO - Running setup.py (path:/tmp/pip-KlPObJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:26 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)) 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:26 INFO - Running setup.py (path:/tmp/pip-XahzLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:27 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)) 03:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:27 INFO - Running setup.py (path:/tmp/pip-_zGOQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:27 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)) 03:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:27 INFO - Running setup.py (path:/tmp/pip-KpJkz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:27 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)) 03:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:27 INFO - Running setup.py (path:/tmp/pip-3JMUjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:27 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)) 03:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:27 INFO - Running setup.py (path:/tmp/pip-qYNxuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:27 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)) 03:41:27 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)) 03:41:27 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)) 03:41:27 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)) 03:41:27 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)) 03:41:27 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)) 03:41:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:29 INFO - Downloading blessings-1.6.tar.gz 03:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:41:29 INFO - Installing collected packages: blessings 03:41:29 INFO - Running setup.py install for blessings 03:41:29 INFO - Successfully installed blessings 03:41:29 INFO - Cleaning up... 03:41:29 INFO - Return code: 0 03:41:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:29 INFO - Reading from file tmpfile_stdout 03:41:29 INFO - Current package versions: 03:41:29 INFO - argparse == 1.2.1 03:41:29 INFO - blessings == 1.6 03:41:29 INFO - blobuploader == 1.2.4 03:41:29 INFO - docopt == 0.6.1 03:41:29 INFO - functools32 == 3.2.3-2 03:41:29 INFO - jsonschema == 2.5.1 03:41:29 INFO - manifestparser == 1.1 03:41:29 INFO - mozInstall == 1.12 03:41:29 INFO - mozcrash == 0.17 03:41:29 INFO - mozdebug == 0.1 03:41:29 INFO - mozdevice == 0.48 03:41:29 INFO - mozfile == 1.2 03:41:29 INFO - mozhttpd == 0.7 03:41:29 INFO - mozinfo == 0.9 03:41:29 INFO - mozleak == 0.1 03:41:29 INFO - mozlog == 3.2 03:41:29 INFO - moznetwork == 0.27 03:41:29 INFO - mozprocess == 0.23 03:41:29 INFO - mozprofile == 0.28 03:41:29 INFO - mozrunner == 6.12 03:41:29 INFO - mozscreenshot == 0.1 03:41:29 INFO - mozsystemmonitor == 0.3 03:41:29 INFO - moztest == 0.7 03:41:29 INFO - mozversion == 1.4 03:41:29 INFO - psutil == 3.1.1 03:41:29 INFO - requests == 1.2.3 03:41:29 INFO - wsgiref == 0.1.2 03:41:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:29 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')]} 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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')]} 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:29 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 03:41:29 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 03:41:29 INFO - Using env: {'DISPLAY': ':0', 03:41:29 INFO - 'HOME': '/home/cltbld', 03:41:29 INFO - 'LANG': 'en_US.UTF-8', 03:41:29 INFO - 'LANGUAGE': 'en_US:en', 03:41:29 INFO - 'LOGNAME': 'cltbld', 03:41:29 INFO - 'MAIL': '/var/mail/cltbld', 03:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:29 INFO - 'MOZ_NO_REMOTE': '1', 03:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:29 INFO - 'NO_EM_RESTART': '1', 03:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:29 INFO - 'PWD': '/builds/slave/test', 03:41:29 INFO - 'SHELL': '/bin/bash', 03:41:29 INFO - 'SHLVL': '1', 03:41:29 INFO - 'TERM': 'linux', 03:41:29 INFO - 'TMOUT': '86400', 03:41:29 INFO - 'USER': 'cltbld', 03:41:29 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:29 INFO - '_': '/tools/buildbot/bin/python'} 03:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 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)) 03:41:29 INFO - Cleaning up... 03:41:29 INFO - Return code: 0 03:41:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:41:29 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')]} 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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')]} 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:29 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 03:41:29 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 03:41:29 INFO - Using env: {'DISPLAY': ':0', 03:41:29 INFO - 'HOME': '/home/cltbld', 03:41:29 INFO - 'LANG': 'en_US.UTF-8', 03:41:29 INFO - 'LANGUAGE': 'en_US:en', 03:41:29 INFO - 'LOGNAME': 'cltbld', 03:41:29 INFO - 'MAIL': '/var/mail/cltbld', 03:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:29 INFO - 'MOZ_NO_REMOTE': '1', 03:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:29 INFO - 'NO_EM_RESTART': '1', 03:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:29 INFO - 'PWD': '/builds/slave/test', 03:41:29 INFO - 'SHELL': '/bin/bash', 03:41:29 INFO - 'SHLVL': '1', 03:41:29 INFO - 'TERM': 'linux', 03:41:29 INFO - 'TMOUT': '86400', 03:41:29 INFO - 'USER': 'cltbld', 03:41:29 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:29 INFO - '_': '/tools/buildbot/bin/python'} 03:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:41:30 INFO - Cleaning up... 03:41:30 INFO - Return code: 0 03:41:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:30 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 0x7f8501364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1830350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, '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': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', '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 03:41:30 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 03:41:30 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 03:41:30 INFO - Using env: {'DISPLAY': ':0', 03:41:30 INFO - 'HOME': '/home/cltbld', 03:41:30 INFO - 'LANG': 'en_US.UTF-8', 03:41:30 INFO - 'LANGUAGE': 'en_US:en', 03:41:30 INFO - 'LOGNAME': 'cltbld', 03:41:30 INFO - 'MAIL': '/var/mail/cltbld', 03:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:30 INFO - 'MOZ_NO_REMOTE': '1', 03:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:30 INFO - 'NO_EM_RESTART': '1', 03:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:30 INFO - 'PWD': '/builds/slave/test', 03:41:30 INFO - 'SHELL': '/bin/bash', 03:41:30 INFO - 'SHLVL': '1', 03:41:30 INFO - 'TERM': 'linux', 03:41:30 INFO - 'TMOUT': '86400', 03:41:30 INFO - 'USER': 'cltbld', 03:41:30 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:30 INFO - '_': '/tools/buildbot/bin/python'} 03:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:30 INFO - Downloading/unpacking flake8 03:41:30 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 03:41:30 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 03:41:30 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 03:41:30 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 03:41:30 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 03:41:30 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 03:41:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:41:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:41:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:41:32 INFO - no previously-included directories found matching 'docs/_build' 03:41:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:41:33 INFO - Running setup.py install for pep8 03:41:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:41:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:41:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:41:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:41:33 INFO - no previously-included directories found matching 'docs/_build' 03:41:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:41:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:41:33 INFO - Cleaning up... 03:41:33 INFO - Return code: 0 03:41:33 INFO - Running post-action listener: _resource_record_post_action 03:41:33 INFO - Running post-action listener: _start_resource_monitoring 03:41:33 INFO - Starting resource monitoring. 03:41:33 INFO - ##### 03:41:33 INFO - ##### Finished create-virtualenv step (success) 03:41:33 INFO - ##### 03:41:33 INFO - ##### 03:41:33 INFO - ##### Running install step. 03:41:33 INFO - ##### 03:41:33 INFO - Running pre-action listener: _resource_record_pre_action 03:41:33 INFO - Running main action method: install 03:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:33 INFO - Reading from file tmpfile_stdout 03:41:33 INFO - Detecting whether we're running mozinstall >=1.0... 03:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:41:33 INFO - Reading from file tmpfile_stdout 03:41:33 INFO - Output received: 03:41:33 INFO - Usage: mozinstall [options] installer 03:41:33 INFO - Options: 03:41:33 INFO - -h, --help show this help message and exit 03:41:33 INFO - -d DEST, --destination=DEST 03:41:33 INFO - Directory to install application into. [default: 03:41:33 INFO - "/builds/slave/test"] 03:41:33 INFO - --app=APP Application being installed. [default: firefox] 03:41:33 INFO - mkdir: /builds/slave/test/build/application 03:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:41:49 INFO - Reading from file tmpfile_stdout 03:41:49 INFO - Output received: 03:41:49 INFO - /builds/slave/test/build/application/firefox/firefox 03:41:49 INFO - Running post-action listener: _resource_record_post_action 03:41:49 INFO - ##### 03:41:49 INFO - ##### Finished install step (success) 03:41:49 INFO - ##### 03:41:49 INFO - ##### 03:41:49 INFO - ##### Running run-tests step. 03:41:49 INFO - ##### 03:41:49 INFO - Running pre-action listener: _resource_record_pre_action 03:41:49 INFO - Running main action method: run_tests 03:41:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:41:49 INFO - Python 2.7.3 03:41:49 INFO - Return code: 0 03:41:49 INFO - grabbing minidump binary from tooltool 03:41:49 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')]} 03:41:49 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 0x18bf1c0>, '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 0x18c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c0550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:41:49 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 03:41:49 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 03:41:49 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 03:41:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:41:49 INFO - Return code: 0 03:41:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:41:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:41:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:41:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:41:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:41:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:41:50 INFO - Return code: 0 03:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 03:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 03:41:50 INFO - Using env: {'DISPLAY': ':0', 03:41:50 INFO - 'HOME': '/home/cltbld', 03:41:50 INFO - 'LANG': 'en_US.UTF-8', 03:41:50 INFO - 'LANGUAGE': 'en_US:en', 03:41:50 INFO - 'LOGNAME': 'cltbld', 03:41:50 INFO - 'MAIL': '/var/mail/cltbld', 03:41:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:41:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:50 INFO - 'MOZ_NO_REMOTE': '1', 03:41:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:41:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:50 INFO - 'NO_EM_RESTART': '1', 03:41:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:50 INFO - 'PWD': '/builds/slave/test', 03:41:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:41:50 INFO - 'SHELL': '/bin/bash', 03:41:50 INFO - 'SHLVL': '1', 03:41:50 INFO - 'TERM': 'linux', 03:41:50 INFO - 'TMOUT': '86400', 03:41:50 INFO - 'USER': 'cltbld', 03:41:50 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007', 03:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:50 INFO - '_': '/tools/buildbot/bin/python'} 03:41:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 03:41:50 INFO - mozversion application_buildid: 20160731234823 03:41:50 INFO - mozversion application_changeset: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff 03:41:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:41:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:41:50 INFO - mozversion application_name: Firefox 03:41:50 INFO - mozversion application_remotingname: firefox-dev 03:41:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:50 INFO - mozversion application_vendor: Mozilla 03:41:50 INFO - mozversion application_version: 49.0a2 03:41:50 INFO - mozversion platform_buildid: 20160731234823 03:41:50 INFO - mozversion platform_changeset: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff 03:41:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:50 INFO - mozversion platform_version: 49.0a2 03:41:50 INFO - using testdate: 1470048110 03:41:50 INFO - actual date: 1470048110 03:41:50 INFO - starting webserver on 'localhost:50836' 03:41:50 INFO - SUITE-START | Running 2 tests 03:41:50 INFO - TEST-START | dromaeo_css 03:41:50 INFO - operating with platform_type : linux_ 03:41:50 INFO - Initialising browser for dromaeo_css test... 03:41:50 INFO - TEST-INFO | started process 2964 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH4jXjG/profile http://localhost:50836/getInfo.html) 03:41:53 INFO - PROCESS | 2964 | __metrics Screen width/height:1600/1200 03:41:53 INFO - PROCESS | 2964 | colorDepth:24 03:41:53 INFO - PROCESS | 2964 | Browser inner width/height: 1024/697 03:41:53 INFO - PROCESS | 2964 | __metrics 03:41:54 INFO - PROCESS | 2964 | 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" 03:41:54 ERROR - PROCESS | 2964 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:41:55 INFO - TEST-INFO | 2964: exit 0 03:41:55 INFO - Browser initialized. 03:41:55 INFO - Running cycle 1/1 for dromaeo_css test... 03:41:55 INFO - TEST-INFO | started process 3056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH4jXjG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:07 INFO - PROCESS | 3056 | RSS: Main: 142639104 03:42:07 INFO - PROCESS | 3056 | 03:42:59 INFO - PROCESS | 3056 | 1470048179232 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] 03:45:06 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:50836/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50836/tests/dromaeo/cssquery-ext.html) 03:45:07 INFO - PROCESS | 3056 | RSS: Main: 254259200 03:45:07 INFO - PROCESS | 3056 | 03:48:09 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:50836/tests/dromaeo/cssquery-ext.html (next: http://localhost:50836/tests/dromaeo/cssquery-jquery.html) 03:48:10 INFO - PROCESS | 3056 | RSS: Main: 228474880 03:48:10 INFO - PROCESS | 3056 | 03:51:12 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:50836/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50836/tests/dromaeo/cssquery-mootools.html) 03:51:13 INFO - PROCESS | 3056 | RSS: Main: 231366656 03:51:13 INFO - PROCESS | 3056 | 03:54:15 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:50836/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50836/tests/dromaeo/cssquery-prototype.html) 03:54:15 INFO - PROCESS | 3056 | RSS: Main: 220778496 03:54:15 INFO - PROCESS | 3056 | 03:57:18 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:50836/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50836/tests/dromaeo/cssquery-yui.html) 03:57:18 INFO - PROCESS | 3056 | RSS: Main: 217219072 03:57:18 INFO - PROCESS | 3056 | 04:00:20 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:50836/tests/dromaeo/cssquery-yui.html (next: http://localhost:50836/tests/dromaeo/cssquery-dojo.html) 04:00:21 INFO - PROCESS | 3056 | RSS: Main: 254701568 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | __start_tp_report 04:00:21 INFO - PROCESS | 3056 | _x_x_mozilla_page_load 04:00:21 INFO - PROCESS | 3056 | _x_x_mozilla_page_load_details 04:00:21 INFO - PROCESS | 3056 | |i|pagename|runs| 04:00:21 INFO - PROCESS | 3056 | |0;dojo.html;3356.3287342531494;3374.1395944034266;3379.864805407784;3387.0685561470577;3417.094469965458;5459.290292262005;5543.168524721289;5565.332160140789;5569;5571.4428557144365;5517.5585953123755;5585.1063829787245;5586.162075688649;5589.944100558993;5627.774889004435;4991.925121123168;5009.474005229453;5040.949590504094;5063.16457784467;5105.591552675795;5004.899902001957;5048.495150484959;5058.4435712071645;5063.265826455162;5074.112030394685;5004.749762511878;5012.198048312271;5021.748912554376;5033.245013248005;5073.365829271341;5008.398992120969;5074.619403544749;5075.314832497639;5087.821926232583;5106.668066575679;4875.536824001714;4931.087748766489;4974.925376119361;4980.576650984671;4992.925106123411;5409.080456322435;5448.509634132947;5453.700046497441;5464.180372944051;5470.316210473691;5781.103928891029;5949;5970.55220858433;5978.730957106941;5986.28164620244;5861.706914654293;5960.493358064569;5963.254593175853;6005.099235114768;6024.608400453956;5606.21512988182;5613.270274864241;5616.831495055114;5623.437656234385;5630.380658127603;5894.8821023579285;5950.494207992369;6092.268927728613;6102.700094033146;6120.571559990847;5779.104238842986;5834.737436815269;5860.238169037998;5865.530757539386;5871;5869.295684517885;5893.11603259506;5905.675187864708;5922.466977972003;5925.555583331267;5740.1102829061565;5821.126830975303;5888.911666325009;5911.261092363455;5917.674527900921;5947.286325640951;5947.791827286111;5949.345571987078;5953.1367951647635;5979.9402005980255;4511.819527218874;4560.156371071363;4560.8175672973375;4562.58936695699;4584.931226031612;3981.960180398217;4010.3382941814275;4044.988752811797;4066.6543343816106;4073.9796301018305;5734.62724922887;5747.109198074305;5749.482546570745;5753.223314852525;5753.626014309057;5989.610675306091;5989.850253743692;6000.039993601003;6001;6035.36628653985;5575.470330318694;5690;5703.771849125988;5710.457506536873;5775.249217601685;5136.794528218904;5141.357330333708;5144.948550514522;5146.614003949719;5148.639595228298;6033.637981721111;6291.182146320949;6335.049742538564;6363.96818015907;6368.29948705642;5864.824055278348;5938.554608404474;5942.405759424196;5986.910196347146;5991.310999235144;6217.497629385601;6238.220222472191;6247.09417134522;6260.561760676709;6265.749370025148;5700.572457065654;5809.2738407699035;5825.155352473952;5827.970860145672;5855.707214639166;5962.433568810956;6019;6025.517958563217;6027.517798576016;6064.848378790565;5865.706714664335;5867.794627188028;5899.941000590111;5902.911456328245;5905.793297234576;5528.612997090165;5559.138333558305;5627.8593035174445;5796.536277097738;5813.593048486566;5617.550595952396;5625.746841392229;5628.831135065954;5629.4652008059165;5654.971725141348;5651.095824668033;5658.349289831722;5689.374168841341;5703.173039909107;5704.486596206361;5553.055980483408;5564.221009058654;5615.3823079462045;5668.291463567054;5701.201831743642 04:00:21 INFO - PROCESS | 3056 | |1;ext.html;599.6162456028143;620.7858288890333;646.2148489585153;651.4657980456027;653.464159389301;32353.67646323539;45969;46554;46622.76688616552;47208.76395618021;44243.77878110613;44675.55324446755;44692;44759.77620111903;44782.104357912904;8306.875396869053;8477.576121193946;8484.066752657203;8728.389012769108;8938.821223575556;8449.662013519483;10654.893451065507;10864.565417383335;10875.673729788119;11158.497867595937;11047.447627618587;11114.333140011584;11116.944415277912;11124.221304508688;11132.387718675469;8366.748997530083;10719.83920241197;10824.24230303879;10894.61868834587;10909.290896091768;10540.420276884844;10928.453577321103;10948;10962.616308429167;10974;44371.33442998357;44689.55310446919;44697.106057878664;44726.32910506345;44746.77626611846;3705.3515634763808;3713.1459764254105;3730.757500762442;3744.2511497700298;3797.5063241778394;33824.661753382396;33870.661293387;33980;34045.31909361799;34092;4502.144592527409;4560.771961401917;4574.222382195002;4624.44506659203;4630.120277147331;398954;402187;402722;403306;406560;65490.67254663696;66096;66354;66429.66785166043;66641;39298.41052384216;40534.79732601377;40581.59418405837;42149.789251054164;62858.685706571174;19079.61840763177;19157;19234;19281.4215573533;19293.614127717367;13198.406071726795;13441.798373024412;13450.46198152063;14277.714445711028;14423.350949207312;19802.306919257757;20694.793052069286;20734.585308294052;20792.272270470763;20836.583268334547;7234.493585449073;7372.926270737331;7388.704451821867;7407.740729074564;7465.328120469184;1015.6242190389455;1122.3995162587976;1148.538698488681;1160.6111952496008;1167.9474423650956;33807.830960845036;33853.32293354168;34375.82812085973;34377.31245375078;34521;1172.9003034741972;1174.1311429542247;1216.0940099625836;1271.1102228439945;1297.6431481342518;17164.742528862076;17752.37866674686;17792.733109003377;17811.554711132325;17959.551011224823;659802;919689;929931;934711;940279;64668;65215.673921630085;65255;65475.34524654692;65599;1316.6181807275975;1419.1272367494123;1460.4596299369302;1465.3625672832352;1492.9178895160653;31328.530072049394;34057.829710851285;34177;34383.93410503992;35453.29093418066;52006.21990670219;52992.20511692251;53097.734511327195;53114.734426327625;53138.73430632977;441789;449669;450170;450405;452874;27496.450070998068;29975.400491990473;30275.243118922204;30788;30957;50116.24825627545;52908;53283.4671653294;53623.46376536185;55627.721861392056;44759;44888.77555612201;44981.1320754717;45608.77195614133;45963.310550341106;43695.78152109219;44470;45159.54840451685;45223.547764521936;45738.54261457343;44041.559584403745;44960.550394496946;45202.09595808;45276.320855187856;45513.54486455093;37315.253694925406;39223;39527.60472395354;39661;40199.1960160801;29605.5559166617;29710.405791883608;29823.254418639706;29932.700672992993;30056.549151762305 04:00:21 INFO - PROCESS | 3056 | |2;jquery.html;433.6444115825023;435.8928322061961;436.3149854728078;438.0734746012184;446.7922416076524;18306.99999999998;19411.70882436763;19854.20583176673;20771.376858694224;20796.792032079673;18899.433017009476;19694;19719.309824156157;20117.698234526495;20254.189832406686;5128.769205385757;5349.7592608332525;5384.811531596395;5393.1370980643105;5397.7571009304675;3841.4814000110046;4230.090530535934;5270.262163297122;5347.144456886879;6586.110875031857;6413.230412350525;6536.411722944934;6572.408483236508;6597.93402065978;6622.966885165568;6190.535709821781;6427.903581446282;6428.582142160744;6432.067350234213;6531.575447595915;3728.5898551159353;5489.3412790465;5761.769529218826;5764.106563482667;5789.507891829199;14466.566003019927;14469.20419376934;14488.927555362261;14574.854251457456;14888.851111488962;2412.3848418653306;2561.2700380391684;2600.7919366450637;2980.882169186555;3088.934317660426;10481.371117732884;10705.1435885129;10799.352038877692;10930.726731831688;10942.945285273603;1955.501347156466;2032.9288474903308;2045.8977051147383;2076.1591555420077;2100.716403285552;87427.56286218592;90157.09842901553;91134.08865911323;91348;91528.54235728845;11822.822657660143;11831.526738930346;11931.403429828473;11985.161038727205;12015.399230038463;17081.743773843402;17144;17175.31298748036;17213.22540485681;17284.740728889075;8811.603477843513;8812.823743525221;8964.282857371394;9021.639134434548;9053.411528250643;8575.485470871768;8592.914070859211;8605.655773769104;8682.218600326001;8725.21473067427;5104.259882317043;5172.793088276502;5195.350581177353;5234.2933703949475;5240.502152295526;2048;2119.5125121222204;2136;2142.2502124256384;2173.5870184664864;993.7813680990171;1022.6727447216967;1054.8681414823147;1063.1016790811752;1104.8784633690289;19727.802721972595;19842.50393740168;20062.39812805589;20227.8988605059;20397;1016.2733645443543;1055.377327376836;1070.4326706845384;1091.9181061420425;1115.9442027898572;10888.401137937331;11321.830172547418;11374.829377559343;11487.310761354345;11500.194986351042;389281;554171;555836;564242;571360;91250;95184.52407738056;100244;103612.48193758982;105499;992.5187032418953;1000.8448690452991;1011.5650270383659;1014.0873214107362;1015.1320620869199;17862.19620117072;17887.642247155243;17907.641847163246;18097.18562664657;18236.270549178415;30251.848740756155;30404.69595304107;30746.538801918436;40719.59280407158;41640;59719;60146;61103;61902.69048654728;62402.68798655977;9127.132922372364;9255.907440925504;9285.907140928504;9334.032511798745;9388.67139650109;9863.063009014133;13651.931740341235;13793.793093103412;13998.160110393408;14136.08115472491;12019.63941081769;12322.383880806103;12390.256584604953;12416.192947458387;12749.8087528689;11872.643820685393;11940.343281119622;11949.462274197507;12255.877441225473;12379.442925068573;12135.089868260093;12175.391230438265;12227.938860305641;12270.693232669255;12313.630591081923;9211.309151813775;9224.631014759603;9275.67535136267;9300.023497532662;9310.906890931275;9580.808383832145;12299.262044277371;12324.81512777327;12367.381630918238;12452 04:00:21 INFO - PROCESS | 3056 | |3;mootools.html;1399.629098288953;1556.6808804195139;1599.408218958985;1783.2866853258704;1897.0893970893972;8968.417052891566;9343.953280233598;9347.158755711984;9360.953195234022;9573.617055317778;9045;9319.860202096957;9576.904230957689;9588.28087893409;9591.37656052356;2151.1180416029433;2412.445137618349;2487.6392923026197;2499.7125330587032;2539.8222124451295;2516.8741562921873;2529.190658989124;2529.557327467695;2531.253280282312;2535.784458321036;2476.158106243877;2525.532776436365;2534.581794003987;2550.0947163756878;2550.6301586270074;2099.6745504446835;2426.478307163968;2480.1567467061195;2496.0015993602524;2503.424212431151;2417.7461366556436;2425.211806163004;2444.1201167579657;2447.2413551799;2506.1103308325464;9498.620055197853;9555.378900371523;9565;9565.37825041377;9566.808663826685;5609.298837645294;5732.5987180897355;5739.598228124033;5771.624844385102;5777.393373695785;3615.403458429356;3691.427828686558;3745.831437585315;3761.0785357587565;3790.298794722985;1219.1587804415014;1240.0823390690914;1285.6271681212438;1289.1813698301553;1353.1001883747297;13815.171089734547;14073.859261407357;14080.859191408159;14082.436702531886;14109.435622575085;5080.898382032338;5173.663711858717;5381.246625472437;5393.5954803389905;5460.754266058037;11065.114790816715;11084.168687348481;11114;11136.276142050741;11145.331280123219;5897.5871688982215;5910.822675319661;5912.497437717842;5917.349091599921;5920.911186332209;4706.1528924793865;5185.351831021122;5285.524302812689;5331.120365139786;5400.081986062379;4260.595243451867;4286.314189729628;4301.09676967834;4342.6308763754805;4346.195953748566;1225.221984040123;1282.9351638140322;1288.9548865789652;1292.8190053392532;1301.73965206959;1064.0689396777818;1066.168388656849;1085.7882712871071;1087.516055355375;1098.373926861697;6593.406593406617;6638.6680665966505;6641.236257830411;6692.431143352869;6773.254941956381;499.79008816297244;506.4226781469128;506.89608630230714;510.32891747352113;510.5583670125315;4604.539546045368;4684.320773487825;4694.694844835075;4697.201475749131;4698.530146985274;11770.234934729215;12200.694982625506;12324.81512777309;12756.744865102646;12771.744565108831;12415.130940834055;12421.689457763629;12460.12779105472;12515.874841251652;12523;9015.864762028576;9174.266058715286;9196.218321442751;9211.539423028957;9234.215091717146;6707.396334329934;6912.896306555506;6948.791536253921;6973.755918542826;7023.473239506955;11111.611093611686;11327.376994265065;11634.825477618011;11675.591354302558;11744.412779361168;11032.944835275772;11511.251768635348;11512.13658975597;11653.30080195191;11860.525578976743;7780.883286750816;7998.440109192534;8027.919720802717;8751.212390884852;8933.330000250136;8867.512286824287;9169.77075573116;9373.343865929319;9532.761680957754;9696.224302055678;8456.704015359433;8643.783905402164;8671.08953559867;8772.78068048304;8838.469691818511;8123.83752324962;8184.877226841483;8196.057453392936;8292.212239837207;8342.374321925758;7359;7579.1284002340435;7581.583012934121;7701.653425595974;7735.9613201933635;5646.88706225865;5713.514351280188;5824.621399609012;5837.67892765902;5881.500072493886;5636.661800291996;5640.520555752642;5645.096784514458;5656.23640808502;5824.213731146241 04:00:21 INFO - PROCESS | 3056 | |4;prototype.html;344.6691176470588;350.8000439749343;351.53772788782766;353.6145601294588;358.66285691450037;5388.541973932215;5405.43242959489;5410.56715462763;5436.885438485111;5443.557457273816;5341.893162136765;5344.9577332420195;5356.276902618145;5363.356397232337;5416.4312747161475;4160.459140311756;4194.853180138696;4237.131388065439;4246.893827654302;4249.150169966019;4235.025944032859;4244.363345498169;4250.97874510627;4267.914641707164;4297.818100022503;4207.611488208884;4248.851290204843;4255.659547236229;4256.212600668886;4261.211675839964;4105.712600117978;4206.936895946563;4243.936340954888;4263.335415365396;4268.253055715253;3759.3609086455367;3763.924721505582;3765.8493660253557;3770.6606405423645;3771.4908487354132;5130.333056702642;5162.586993040585;5167.509086636764;5182.274481572583;5209.7134657593815;4556.567126123046;4833.009233107205;4854.660173787836;4886.291487734258;4901.191303434929;5584.5811564132855;5595.356533998602;5611.551075913917;5615.820677657656;5635.63368381058;4055.2700513907484;4061.3704875744306;4073;4075.612816782401;4093.385992101179;5716.228309178249;5827.805299913508;5832.12518122285;5865.94134058654;5873.974902924954;4167.624913757776;4175.331946888544;4185.644220241253;4193.874183774492;4206.894827629272;4253.404523366731;4260.744355338689;4261.041265715189;4268.445102136702;4281;4748.786304616231;4839.370881785416;4842.249451335048;4865.489123642037;4873.270968257787;5560.833175004756;5587.916181257285;5608.551315894719;5621.578381621394;5631.521320687705;1407.96514561797;1436.289036926707;1450.8113945187058;1451.4557041109583;1451.7894905238682;898.2006014646969;916.7662246127195;918.0865039285953;918.9494577798151;929.2937367600672;4933.777979990838;4967.82612608557;4972.701637901738;4976.353074100416;4983.9252411213865;5092.312537807423;5121.692698438105;5178.766955487012;5185.481451854785;5202.739863006835;4693.647976401783;4973.875653108629;4989.201727723546;5007.674501157486;5035.899282014412;2696.8673157273993;2743.382738883775;2750.8074434789373;2793.003002852929;2799.90200342987;5294.755732402882;5383.71192243295;5390.299261096033;5403.918941215885;5426.240326354314;5055.317532133188;5085.364329458817;5233.921491177635;5235.4502777207845;5621.887562248814;5901.232839730807;6046;6074.362191969868;6113.144159817629;6125.356837531995;5769.278840144982;5835.67903765297;5839.445252700986;5854.326752423526;5857.4435428634215;5379.515843574097;5629.718514074198;5727.570432217517;5728.226689396878;5746.655200687972;6059.72731227087;6079.665618390854;6081.756729730761;6086.573939824169;6096.420840020191;4109.876703698894;4247.086876321606;4258.318669013067;4272.295071313199;4288.335308027261;5702.543796496353;5718.370979192202;5736.68448235351;5744.913826292693;5763.567732420001;5608.158776183605;5622.5783066271015;5648.604597678123;5658.660480371191;5673.290838645169;5417.133258678593;5551.723103686094;5576.860578485408;5590.217369568345;5592.804251851166;5487.835364939058;5508.256385387921;5513.807016754555;5548.944510554843;5563.610547261653;5597.6641401516035;5610.4670056344585;5655.377908430151;5664.745086471036;5667.744951477276;5382.703951282717;5428.56571474289;5507.531859791962;5551.333839939233;5565.833025009256 04:00:21 INFO - PROCESS | 3056 | |5;yui.html;724.652166959859;745.6197339356926;754.1365136918229;780.1730166024014;809.8623234050211;15594.454194103195;15879.523614291575;16793.32826686931;16919.49241522753;16927.06901120441;15398.30707618157;15549.611259718513;15556.922215388908;15576.221188940564;15594.220288985534;700.5411455496658;839.1608391608407;841.3773807382554;856.8029353248756;860.8106216632331;851.9318454523623;857.2756021162139;858.9398742088056;860.7675927499571;860.9913900861005;896.256107430834;898.1871406377206;901.4320977783987;903.1194585279366;903.327021369081;749.8500299940007;881.8897637795276;892.584947999177;893.4103491695515;895.9776005599847;658.1016911915249;661.3386613386614;661.9900701489481;662.1987395251751;662.2847324889108;15335.923320383326;15339.693206135815;15370.615734606612;15385.153816540085;15392.769108463383;14142.434302627882;14497.782533262009;14499.855001449956;14538.127712337293;14624.561263162122;12664.4301006454;12952.546660866825;13147.342632868318;13185.274809885452;13409.329533523382;4626.421697287839;4665.743384113873;4702.247640377524;4756.167670657655;4867.829625963074;75882;75894;75926;77249;79195.20804791879;11319.830202546967;11550.075993920465;11565.710857228636;11616.825747613793;11621.360825154528;18653.44039678812;18676.813231867778;18753.218444247774;18758.53103672419;18801.623967520572;8656.697015604424;8705.608247628872;8723.08407617191;8726.738197854074;8926.464412135292;4898.877528061756;4907.288443175791;4921.827736029293;4971;4975.353204083446;3538.3454060998797;3647.8723244686307;3652.4886515887797;3653.5433070866143;3655.469956856241;591.5740666719955;647.5628950458422;669.491186698113;724.5725022236905;726.4043484342789;639.5746828359182;721.8989341492195;735.334522257358;741.499487845702;790.3084800799301;10534.157267418741;10897.618583349546;10940.12479001678;11054.778904421866;11119.555217791198;684.5345165287653;696.6481926627021;702.8980797784293;703.6938931564683;746.0860445953664;4744.667873248908;4915.926261106087;4963.5036496350795;5008.248762685626;5014.272930425068;123593.38203309105;126579;126912;129484;135201;66900.33099669039;98940.01059989308;99384.50307748414;110979.44510277397;112827;59511;59887.10169347463;67082;97445.5127724371;100104.49947750215;9418.18308756418;9487.193588544775;9605.18355939753;9639.759006024906;9719;54915.45084549103;55384.72307638436;55554.44445555493;55768.44231557632;57090.14364784448;74040.62979685067;75564;81868.59065704633;82061.58969205355;84814.57592711996;13706.24615646149;13768;13830.65423364424;14013.089149205269;14134.717305654034;11267.26762760418;14858.182799946107;14862.554123376316;14929.850701492845;15434;13982;14344.282785860456;14393.7840932384;14544.345504452536;14815.555533334018;14051.929740351234;14099.506517271846;14109.012369134472;14254.322730752638;14294.928525357307;13860.237686927316;14106.435742570593;14306.356213970188;14757.114573125647;14781.556553302988;9095.363324567217;9917.256205784452;12161.270323780602;12475.563355282522;12903.09678322546;12092.576759813364;12280.140390172959;12318.137730358789;12435;12556.309402982559 04:00:21 INFO - PROCESS | 3056 | __end_tp_report 04:00:21 INFO - PROCESS | 3056 | __start_cc_report 04:00:21 INFO - PROCESS | 3056 | _x_x_mozilla_cycle_collect,953 04:00:21 INFO - PROCESS | 3056 | __end_cc_report 04:00:21 INFO - PROCESS | 3056 | __startTimestamp1470049221238__endTimestamp 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | ------- Summary: start ------- 04:00:21 INFO - PROCESS | 3056 | Number of tests: 6 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | [#0] dojo.html Cycles:165 Average:5519.91 Median:5700.57 stddev:616.63 (10.8%) stddev-sans-first:594.70 04:00:21 INFO - PROCESS | 3056 | Values: 3356.3 3374.1 3379.9 3387.1 3417.1 5459.3 5543.2 5565.3 5569.0 5571.4 5517.6 5585.1 5586.2 5589.9 5627.8 4991.9 5009.5 5040.9 5063.2 5105.6 5004.9 5048.5 5058.4 5063.3 5074.1 5004.7 5012.2 5021.7 5033.2 5073.4 5008.4 5074.6 5075.3 5087.8 5106.7 4875.5 4931.1 4974.9 4980.6 4992.9 5409.1 5448.5 5453.7 5464.2 5470.3 5781.1 5949.0 5970.6 5978.7 5986.3 5861.7 5960.5 5963.3 6005.1 6024.6 5606.2 5613.3 5616.8 5623.4 5630.4 5894.9 5950.5 6092.3 6102.7 6120.6 5779.1 5834.7 5860.2 5865.5 5871.0 5869.3 5893.1 5905.7 5922.5 5925.6 5740.1 5821.1 5888.9 5911.3 5917.7 5947.3 5947.8 5949.3 5953.1 5979.9 4511.8 4560.2 4560.8 4562.6 4584.9 3982.0 4010.3 4045.0 4066.7 4074.0 5734.6 5747.1 5749.5 5753.2 5753.6 5989.6 5989.9 6000.0 6001.0 6035.4 5575.5 5690.0 5703.8 5710.5 5775.2 5136.8 5141.4 5144.9 5146.6 5148.6 6033.6 6291.2 6335.0 6364.0 6368.3 5864.8 5938.6 5942.4 5986.9 5991.3 6217.5 6238.2 6247.1 6260.6 6265.7 5700.6 5809.3 5825.2 5828.0 5855.7 5962.4 6019.0 6025.5 6027.5 6064.8 5865.7 5867.8 5899.9 5902.9 5905.8 5528.6 5559.1 5627.9 5796.5 5813.6 5617.6 5625.7 5628.8 5629.5 5655.0 5651.1 5658.3 5689.4 5703.2 5704.5 5553.1 5564.2 5615.4 5668.3 5701.2 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | [#1] ext.html Cycles:180 Average:72856.23 Median:221193.10 stddev:166341.84 (75.2%) stddev-sans-first:166720.01 04:00:21 INFO - PROCESS | 3056 | Values: 599.6 620.8 646.2 651.5 653.5 32353.7 45969.0 46554.0 46622.8 47208.8 44243.8 44675.6 44692.0 44759.8 44782.1 8306.9 8477.6 8484.1 8728.4 8938.8 8449.7 10654.9 10864.6 10875.7 11158.5 11047.4 11114.3 11116.9 11124.2 11132.4 8366.7 10719.8 10824.2 10894.6 10909.3 10540.4 10928.5 10948.0 10962.6 10974.0 44371.3 44689.6 44697.1 44726.3 44746.8 3705.4 3713.1 3730.8 3744.3 3797.5 33824.7 33870.7 33980.0 34045.3 34092.0 4502.1 4560.8 4574.2 4624.4 4630.1 398954.0 402187.0 402722.0 403306.0 406560.0 65490.7 66096.0 66354.0 66429.7 66641.0 39298.4 40534.8 40581.6 42149.8 62858.7 19079.6 19157.0 19234.0 19281.4 19293.6 13198.4 13441.8 13450.5 14277.7 14423.4 19802.3 20694.8 20734.6 20792.3 20836.6 7234.5 7372.9 7388.7 7407.7 7465.3 1015.6 1122.4 1148.5 1160.6 1167.9 33807.8 33853.3 34375.8 34377.3 34521.0 1172.9 1174.1 1216.1 1271.1 1297.6 17164.7 17752.4 17792.7 17811.6 17959.6 659802.0 919689.0 929931.0 934711.0 940279.0 64668.0 65215.7 65255.0 65475.3 65599.0 1316.6 1419.1 1460.5 1465.4 1492.9 31328.5 34057.8 34177.0 34383.9 35453.3 52006.2 52992.2 53097.7 53114.7 53138.7 441789.0 449669.0 450170.0 450405.0 452874.0 27496.5 29975.4 30275.2 30788.0 30957.0 50116.2 52908.0 53283.5 53623.5 55627.7 44759.0 44888.8 44981.1 45608.8 45963.3 43695.8 44470.0 45159.5 45223.5 45738.5 44041.6 44960.6 45202.1 45276.3 45513.5 37315.3 39223.0 39527.6 39661.0 40199.2 29605.6 29710.4 29823.3 29932.7 30056.5 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | [#2] jquery.html Cycles:180 Average:30489.39 Median:2127.76 stddev:87757.59 (4124.4%) stddev-sans-first:87974.76 04:00:21 INFO - PROCESS | 3056 | Values: 433.6 435.9 436.3 438.1 446.8 18307.0 19411.7 19854.2 20771.4 20796.8 18899.4 19694.0 19719.3 20117.7 20254.2 5128.8 5349.8 5384.8 5393.1 5397.8 3841.5 4230.1 5270.3 5347.1 6586.1 6413.2 6536.4 6572.4 6597.9 6623.0 6190.5 6427.9 6428.6 6432.1 6531.6 3728.6 5489.3 5761.8 5764.1 5789.5 14466.6 14469.2 14488.9 14574.9 14888.9 2412.4 2561.3 2600.8 2980.9 3088.9 10481.4 10705.1 10799.4 10930.7 10942.9 1955.5 2032.9 2045.9 2076.2 2100.7 87427.6 90157.1 91134.1 91348.0 91528.5 11822.8 11831.5 11931.4 11985.2 12015.4 17081.7 17144.0 17175.3 17213.2 17284.7 8811.6 8812.8 8964.3 9021.6 9053.4 8575.5 8592.9 8605.7 8682.2 8725.2 5104.3 5172.8 5195.4 5234.3 5240.5 2048.0 2119.5 2136.0 2142.3 2173.6 993.8 1022.7 1054.9 1063.1 1104.9 19727.8 19842.5 20062.4 20227.9 20397.0 1016.3 1055.4 1070.4 1091.9 1115.9 10888.4 11321.8 11374.8 11487.3 11500.2 389281.0 554171.0 555836.0 564242.0 571360.0 91250.0 95184.5 100244.0 103612.5 105499.0 992.5 1000.8 1011.6 1014.1 1015.1 17862.2 17887.6 17907.6 18097.2 18236.3 30251.8 30404.7 30746.5 40719.6 41640.0 59719.0 60146.0 61103.0 61902.7 62402.7 9127.1 9255.9 9285.9 9334.0 9388.7 9863.1 13651.9 13793.8 13998.2 14136.1 12019.6 12322.4 12390.3 12416.2 12749.8 11872.6 11940.3 11949.5 12255.9 12379.4 12135.1 12175.4 12227.9 12270.7 12313.6 9211.3 9224.6 9275.7 9300.0 9310.9 9580.8 12299.3 12324.8 12367.4 12452.0 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | [#3] mootools.html Cycles:180 Average:6404.05 Median:2602.97 stddev:3729.45 (143.3%) stddev-sans-first:3720.95 04:00:21 INFO - PROCESS | 3056 | Values: 1399.6 1556.7 1599.4 1783.3 1897.1 8968.4 9344.0 9347.2 9361.0 9573.6 9045.0 9319.9 9576.9 9588.3 9591.4 2151.1 2412.4 2487.6 2499.7 2539.8 2516.9 2529.2 2529.6 2531.3 2535.8 2476.2 2525.5 2534.6 2550.1 2550.6 2099.7 2426.5 2480.2 2496.0 2503.4 2417.7 2425.2 2444.1 2447.2 2506.1 9498.6 9555.4 9565.0 9565.4 9566.8 5609.3 5732.6 5739.6 5771.6 5777.4 3615.4 3691.4 3745.8 3761.1 3790.3 1219.2 1240.1 1285.6 1289.2 1353.1 13815.2 14073.9 14080.9 14082.4 14109.4 5080.9 5173.7 5381.2 5393.6 5460.8 11065.1 11084.2 11114.0 11136.3 11145.3 5897.6 5910.8 5912.5 5917.3 5920.9 4706.2 5185.4 5285.5 5331.1 5400.1 4260.6 4286.3 4301.1 4342.6 4346.2 1225.2 1282.9 1289.0 1292.8 1301.7 1064.1 1066.2 1085.8 1087.5 1098.4 6593.4 6638.7 6641.2 6692.4 6773.3 499.8 506.4 506.9 510.3 510.6 4604.5 4684.3 4694.7 4697.2 4698.5 11770.2 12200.7 12324.8 12756.7 12771.7 12415.1 12421.7 12460.1 12515.9 12523.0 9015.9 9174.3 9196.2 9211.5 9234.2 6707.4 6912.9 6948.8 6973.8 7023.5 11111.6 11327.4 11634.8 11675.6 11744.4 11032.9 11511.3 11512.1 11653.3 11860.5 7780.9 7998.4 8027.9 8751.2 8933.3 8867.5 9169.8 9373.3 9532.8 9696.2 8456.7 8643.8 8671.1 8772.8 8838.5 8123.8 8184.9 8196.1 8292.2 8342.4 7359.0 7579.1 7581.6 7701.7 7736.0 5646.9 5713.5 5824.6 5837.7 5881.5 5636.7 5640.5 5645.1 5656.2 5824.2 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | [#4] prototype.html Cycles:180 Average:4678.42 Median:5206.23 stddev:1363.01 (26.2%) stddev-sans-first:1327.45 04:00:21 INFO - PROCESS | 3056 | Values: 344.7 350.8 351.5 353.6 358.7 5388.5 5405.4 5410.6 5436.9 5443.6 5341.9 5345.0 5356.3 5363.4 5416.4 4160.5 4194.9 4237.1 4246.9 4249.2 4235.0 4244.4 4251.0 4267.9 4297.8 4207.6 4248.9 4255.7 4256.2 4261.2 4105.7 4206.9 4243.9 4263.3 4268.3 3759.4 3763.9 3765.8 3770.7 3771.5 5130.3 5162.6 5167.5 5182.3 5209.7 4556.6 4833.0 4854.7 4886.3 4901.2 5584.6 5595.4 5611.6 5615.8 5635.6 4055.3 4061.4 4073.0 4075.6 4093.4 5716.2 5827.8 5832.1 5865.9 5874.0 4167.6 4175.3 4185.6 4193.9 4206.9 4253.4 4260.7 4261.0 4268.4 4281.0 4748.8 4839.4 4842.2 4865.5 4873.3 5560.8 5587.9 5608.6 5621.6 5631.5 1408.0 1436.3 1450.8 1451.5 1451.8 898.2 916.8 918.1 918.9 929.3 4933.8 4967.8 4972.7 4976.4 4983.9 5092.3 5121.7 5178.8 5185.5 5202.7 4693.6 4973.9 4989.2 5007.7 5035.9 2696.9 2743.4 2750.8 2793.0 2799.9 5294.8 5383.7 5390.3 5403.9 5426.2 5055.3 5085.4 5233.9 5235.5 5621.9 5901.2 6046.0 6074.4 6113.1 6125.4 5769.3 5835.7 5839.4 5854.3 5857.4 5379.5 5629.7 5727.6 5728.2 5746.7 6059.7 6079.7 6081.8 6086.6 6096.4 4109.9 4247.1 4258.3 4272.3 4288.3 5702.5 5718.4 5736.7 5744.9 5763.6 5608.2 5622.6 5648.6 5658.7 5673.3 5417.1 5551.7 5576.9 5590.2 5592.8 5487.8 5508.3 5513.8 5548.9 5563.6 5597.7 5610.5 5655.4 5664.7 5667.7 5382.7 5428.6 5507.5 5551.3 5565.8 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - PROCESS | 3056 | [#5] yui.html Cycles:180 Average:21374.78 Median:4812.00 stddev:31164.48 (647.6%) stddev-sans-first:31213.33 04:00:21 INFO - PROCESS | 3056 | Values: 724.7 745.6 754.1 780.2 809.9 15594.5 15879.5 16793.3 16919.5 16927.1 15398.3 15549.6 15556.9 15576.2 15594.2 700.5 839.2 841.4 856.8 860.8 851.9 857.3 858.9 860.8 861.0 896.3 898.2 901.4 903.1 903.3 749.9 881.9 892.6 893.4 896.0 658.1 661.3 662.0 662.2 662.3 15335.9 15339.7 15370.6 15385.2 15392.8 14142.4 14497.8 14499.9 14538.1 14624.6 12664.4 12952.5 13147.3 13185.3 13409.3 4626.4 4665.7 4702.2 4756.2 4867.8 75882.0 75894.0 75926.0 77249.0 79195.2 11319.8 11550.1 11565.7 11616.8 11621.4 18653.4 18676.8 18753.2 18758.5 18801.6 8656.7 8705.6 8723.1 8726.7 8926.5 4898.9 4907.3 4921.8 4971.0 4975.4 3538.3 3647.9 3652.5 3653.5 3655.5 591.6 647.6 669.5 724.6 726.4 639.6 721.9 735.3 741.5 790.3 10534.2 10897.6 10940.1 11054.8 11119.6 684.5 696.6 702.9 703.7 746.1 4744.7 4915.9 4963.5 5008.2 5014.3 123593.4 126579.0 126912.0 129484.0 135201.0 66900.3 98940.0 99384.5 110979.4 112827.0 59511.0 59887.1 67082.0 97445.5 100104.5 9418.2 9487.2 9605.2 9639.8 9719.0 54915.5 55384.7 55554.4 55768.4 57090.1 74040.6 75564.0 81868.6 82061.6 84814.6 13706.2 13768.0 13830.7 14013.1 14134.7 11267.3 14858.2 14862.6 14929.9 15434.0 13982.0 14344.3 14393.8 14544.3 14815.6 14051.9 14099.5 14109.0 14254.3 14294.9 13860.2 14106.4 14306.4 14757.1 14781.6 9095.4 9917.3 12161.3 12475.6 12903.1 12092.6 12280.1 12318.1 12435.0 12556.3 04:00:21 INFO - PROCESS | 3056 | -------- Summary: end -------- 04:00:21 INFO - PROCESS | 3056 | 04:00:21 INFO - TEST-INFO | 3056: exit 0 04:00:21 INFO - TEST-OK | dromaeo_css | took 1111629ms 04:00:21 INFO - TEST-START | kraken 04:00:21 INFO - operating with platform_type : linux_ 04:00:21 INFO - Initialising browser for kraken test... 04:00:21 INFO - TEST-INFO | started process 3301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp89b1s_/profile http://localhost:50836/getInfo.html) 04:00:25 INFO - PROCESS | 3301 | __metrics Screen width/height:1600/1200 04:00:25 INFO - PROCESS | 3301 | colorDepth:24 04:00:25 INFO - PROCESS | 3301 | Browser inner width/height: 1024/697 04:00:25 INFO - PROCESS | 3301 | __metrics 04:00:26 INFO - PROCESS | 3301 | 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" 04:00:26 ERROR - PROCESS | 3301 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:00:27 INFO - TEST-INFO | 3301: exit 0 04:00:27 INFO - Browser initialized. 04:00:27 INFO - Running cycle 1/1 for kraken test... 04:00:27 INFO - TEST-INFO | started process 3390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp89b1s_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:39 INFO - PROCESS | 3390 | RSS: Main: 142393344 04:00:39 INFO - PROCESS | 3390 | 04:00:41 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50836/tests/kraken/driver.html?testName=audio-beat-detection) 04:00:42 INFO - PROCESS | 3390 | RSS: Main: 193269760 04:00:42 INFO - PROCESS | 3390 | 04:00:45 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50836/tests/kraken/driver.html?testName=audio-dft) 04:00:45 INFO - PROCESS | 3390 | RSS: Main: 192397312 04:00:45 INFO - PROCESS | 3390 | 04:00:48 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50836/tests/kraken/driver.html?testName=audio-fft) 04:00:49 INFO - PROCESS | 3390 | RSS: Main: 278061056 04:00:49 INFO - PROCESS | 3390 | 04:00:51 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50836/tests/kraken/driver.html?testName=audio-oscillator) 04:00:51 INFO - PROCESS | 3390 | RSS: Main: 186621952 04:00:51 INFO - PROCESS | 3390 | 04:00:53 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50836/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:00:54 INFO - PROCESS | 3390 | RSS: Main: 186966016 04:00:54 INFO - PROCESS | 3390 | 04:00:57 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50836/tests/kraken/driver.html?testName=imaging-darkroom) 04:00:58 INFO - PROCESS | 3390 | RSS: Main: 221818880 04:00:58 INFO - PROCESS | 3390 | 04:01:01 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50836/tests/kraken/driver.html?testName=imaging-desaturate) 04:01:02 INFO - PROCESS | 3390 | RSS: Main: 222425088 04:01:02 INFO - PROCESS | 3390 | 04:01:04 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50836/tests/kraken/driver.html?testName=json-parse-financial) 04:01:05 INFO - PROCESS | 3390 | RSS: Main: 222085120 04:01:05 INFO - PROCESS | 3390 | 04:01:06 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50836/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:01:07 INFO - PROCESS | 3390 | RSS: Main: 198864896 04:01:07 INFO - PROCESS | 3390 | 04:01:09 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:01:09 INFO - PROCESS | 3390 | RSS: Main: 203776000 04:01:09 INFO - PROCESS | 3390 | 04:01:11 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:01:12 INFO - PROCESS | 3390 | RSS: Main: 212971520 04:01:12 INFO - PROCESS | 3390 | 04:01:14 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:01:15 INFO - PROCESS | 3390 | RSS: Main: 214958080 04:01:15 INFO - PROCESS | 3390 | 04:01:18 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:01:18 INFO - PROCESS | 3390 | RSS: Main: 215318528 04:01:18 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | Cycle 1(1): loaded http://localhost:50836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50836/tests/kraken/driver.html?testName=ai-astar) 04:01:20 INFO - PROCESS | 3390 | RSS: Main: 257961984 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | __start_tp_report 04:01:20 INFO - PROCESS | 3390 | _x_x_mozilla_page_load 04:01:20 INFO - PROCESS | 3390 | _x_x_mozilla_page_load_details 04:01:20 INFO - PROCESS | 3390 | |i|pagename|runs| 04:01:20 INFO - PROCESS | 3390 | |0;ai-astar;110;103;109;109;110;109;110;111;108;110 04:01:20 INFO - PROCESS | 3390 | |1;audio-beat-detection;152;122;126;187;124;124;146;130;157;135 04:01:20 INFO - PROCESS | 3390 | |2;audio-dft;160;161;160;161;160;161;159;159;160;161 04:01:20 INFO - PROCESS | 3390 | |3;audio-fft;75;75;75;116;75;75;75;75;99;74 04:01:20 INFO - PROCESS | 3390 | |4;audio-oscillator;84;83;118;83;85;84;115;84;85;135 04:01:20 INFO - PROCESS | 3390 | |5;imaging-gaussian-blur;117;118;117;117;117;116;116;118;117;117 04:01:20 INFO - PROCESS | 3390 | |6;imaging-darkroom;146;145;147;147;147;145;145;145;145;146 04:01:20 INFO - PROCESS | 3390 | |7;imaging-desaturate;94;95;95;95;95;95;95;96;94;95 04:01:20 INFO - PROCESS | 3390 | |8;json-parse-financial;50;50;52;57;52;56;54;52;59;52 04:01:20 INFO - PROCESS | 3390 | |9;json-stringify-tinderbox;61;61;60;60;60;61;61;61;61;60 04:01:20 INFO - PROCESS | 3390 | |10;stanford-crypto-aes;76;81;78;83;79;78;76;77;101;76 04:01:20 INFO - PROCESS | 3390 | |11;stanford-crypto-ccm;119;118;126;124;115;121;115;123;142;118 04:01:20 INFO - PROCESS | 3390 | |12;stanford-crypto-pbkdf2;153;153;156;154;158;154;154;155;153;152 04:01:20 INFO - PROCESS | 3390 | |13;stanford-crypto-sha256-iterative;62;62;60;64;60;60;63;59;58;63 04:01:20 INFO - PROCESS | 3390 | __end_tp_report 04:01:20 INFO - PROCESS | 3390 | __start_cc_report 04:01:20 INFO - PROCESS | 3390 | _x_x_mozilla_cycle_collect,1557 04:01:20 INFO - PROCESS | 3390 | __end_cc_report 04:01:20 INFO - PROCESS | 3390 | __startTimestamp1470049280641__endTimestamp 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | ------- Summary: start ------- 04:01:20 INFO - PROCESS | 3390 | Number of tests: 14 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.23 (2.0%) stddev-sans-first:2.33 04:01:20 INFO - PROCESS | 3390 | Values: 110.0 103.0 109.0 109.0 110.0 109.0 110.0 111.0 108.0 110.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:140.50 stddev:20.64 (14.7%) stddev-sans-first:21.45 04:01:20 INFO - PROCESS | 3390 | Values: 152.0 122.0 126.0 187.0 124.0 124.0 146.0 130.0 157.0 135.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 04:01:20 INFO - PROCESS | 3390 | Values: 160.0 161.0 160.0 161.0 160.0 161.0 159.0 159.0 160.0 161.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:14.33 (19.1%) stddev-sans-first:15.01 04:01:20 INFO - PROCESS | 3390 | Values: 75.0 75.0 75.0 116.0 75.0 75.0 75.0 75.0 99.0 74.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:19.37 (23.1%) stddev-sans-first:20.08 04:01:20 INFO - PROCESS | 3390 | Values: 84.0 83.0 118.0 83.0 85.0 84.0 115.0 84.0 85.0 135.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 04:01:20 INFO - PROCESS | 3390 | Values: 117.0 118.0 117.0 117.0 117.0 116.0 116.0 118.0 117.0 117.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 04:01:20 INFO - PROCESS | 3390 | Values: 146.0 145.0 147.0 147.0 147.0 145.0 145.0 145.0 145.0 146.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 04:01:20 INFO - PROCESS | 3390 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 94.0 95.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:3.03 (5.7%) stddev-sans-first:2.95 04:01:20 INFO - PROCESS | 3390 | Values: 50.0 50.0 52.0 57.0 52.0 56.0 54.0 52.0 59.0 52.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 04:01:20 INFO - PROCESS | 3390 | Values: 61.0 61.0 60.0 60.0 60.0 61.0 61.0 61.0 61.0 60.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:78.00 stddev:7.56 (9.7%) stddev-sans-first:7.84 04:01:20 INFO - PROCESS | 3390 | Values: 76.0 81.0 78.0 83.0 79.0 78.0 76.0 77.0 101.0 76.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:122.00 stddev:7.89 (6.5%) stddev-sans-first:8.29 04:01:20 INFO - PROCESS | 3390 | Values: 119.0 118.0 126.0 124.0 115.0 121.0 115.0 123.0 142.0 118.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:154.00 stddev:1.75 (1.1%) stddev-sans-first:1.80 04:01:20 INFO - PROCESS | 3390 | Values: 153.0 153.0 156.0 154.0 158.0 154.0 154.0 155.0 153.0 152.0 04:01:20 INFO - PROCESS | 3390 | 04:01:20 INFO - PROCESS | 3390 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:62.00 stddev:1.97 (3.2%) stddev-sans-first:2.06 04:01:20 INFO - PROCESS | 3390 | Values: 62.0 62.0 60.0 64.0 60.0 60.0 63.0 59.0 58.0 63.0 04:01:20 INFO - PROCESS | 3390 | -------- Summary: end -------- 04:01:20 INFO - PROCESS | 3390 | 04:01:21 INFO - TEST-INFO | 3390: exit 0 04:01:21 INFO - TEST-OK | kraken | took 59913ms 04:01:21 INFO - SUITE-END | took 1171s 04:01:21 INFO - Completed test suite (00:19:31) 04:01:21 INFO - javascript benchmark 04:01:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3356.3287342531494, 3374.1395944034266, 3379.864805407784, 3387.0685561470577, 3417.094469965458, 5459.290292262005, 5543.168524721289, 5565.332160140789, 5569.0, 5571.4428557144365, 5517.5585953123755, 5585.1063829787245, 5586.162075688649, 5589.944100558993, 5627.774889004435, 4991.925121123168, 5009.474005229453, 5040.949590504094, 5063.16457784467, 5105.591552675795, 5004.899902001957, 5048.495150484959, 5058.4435712071645, 5063.265826455162, 5074.112030394685, 5004.749762511878, 5012.198048312271, 5021.748912554376, 5033.245013248005, 5073.365829271341, 5008.398992120969, 5074.619403544749, 5075.314832497639, 5087.821926232583, 5106.668066575679, 4875.536824001714, 4931.087748766489, 4974.925376119361, 4980.576650984671, 4992.925106123411, 5409.080456322435, 5448.509634132947, 5453.700046497441, 5464.180372944051, 5470.316210473691, 5781.103928891029, 5949.0, 5970.55220858433, 5978.730957106941, 5986.28164620244, 5861.706914654293, 5960.493358064569, 5963.254593175853, 6005.099235114768, 6024.608400453956, 5606.21512988182, 5613.270274864241, 5616.831495055114, 5623.437656234385, 5630.380658127603, 5894.8821023579285, 5950.494207992369, 6092.268927728613, 6102.700094033146, 6120.571559990847, 5779.104238842986, 5834.737436815269, 5860.238169037998, 5865.530757539386, 5871.0, 5869.295684517885, 5893.11603259506, 5905.675187864708, 5922.466977972003, 5925.555583331267, 5740.1102829061565, 5821.126830975303, 5888.911666325009, 5911.261092363455, 5917.674527900921, 5947.286325640951, 5947.791827286111, 5949.345571987078, 5953.1367951647635, 5979.9402005980255, 4511.819527218874, 4560.156371071363, 4560.8175672973375, 4562.58936695699, 4584.931226031612, 3981.960180398217, 4010.3382941814275, 4044.988752811797, 4066.6543343816106, 4073.9796301018305, 5734.62724922887, 5747.109198074305, 5749.482546570745, 5753.223314852525, 5753.626014309057, 5989.610675306091, 5989.850253743692, 6000.039993601003, 6001.0, 6035.36628653985, 5575.470330318694, 5690.0, 5703.771849125988, 5710.457506536873, 5775.249217601685, 5136.794528218904, 5141.357330333708, 5144.948550514522, 5146.614003949719, 5148.639595228298, 6033.637981721111, 6291.182146320949, 6335.049742538564, 6363.96818015907, 6368.29948705642, 5864.824055278348, 5938.554608404474, 5942.405759424196, 5986.910196347146, 5991.310999235144, 6217.497629385601, 6238.220222472191, 6247.09417134522, 6260.561760676709, 6265.749370025148, 5700.572457065654, 5809.2738407699035, 5825.155352473952, 5827.970860145672, 5855.707214639166, 5962.433568810956, 6019.0, 6025.517958563217, 6027.517798576016, 6064.848378790565, 5865.706714664335, 5867.794627188028, 5899.941000590111, 5902.911456328245, 5905.793297234576, 5528.612997090165, 5559.138333558305, 5627.8593035174445, 5796.536277097738, 5813.593048486566, 5617.550595952396, 5625.746841392229, 5628.831135065954, 5629.4652008059165, 5654.971725141348, 5651.095824668033, 5658.349289831722, 5689.374168841341, 5703.173039909107, 5704.486596206361, 5553.055980483408, 5564.221009058654, 5615.3823079462045, 5668.291463567054, 5701.201831743642], "value": 5480.040114314793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.6162456028143, 620.7858288890333, 646.2148489585153, 651.4657980456027, 653.464159389301, 32353.67646323539, 45969.0, 46554.0, 46622.76688616552, 47208.76395618021, 44243.77878110613, 44675.55324446755, 44692.0, 44759.77620111903, 44782.104357912904, 8306.875396869053, 8477.576121193946, 8484.066752657203, 8728.389012769108, 8938.821223575556, 8449.662013519483, 10654.893451065507, 10864.565417383335, 10875.673729788119, 11158.497867595937, 11047.447627618587, 11114.333140011584, 11116.944415277912, 11124.221304508688, 11132.387718675469, 8366.748997530083, 10719.83920241197, 10824.24230303879, 10894.61868834587, 10909.290896091768, 10540.420276884844, 10928.453577321103, 10948.0, 10962.616308429167, 10974.0, 44371.33442998357, 44689.55310446919, 44697.106057878664, 44726.32910506345, 44746.77626611846, 3705.3515634763808, 3713.1459764254105, 3730.757500762442, 3744.2511497700298, 3797.5063241778394, 33824.661753382396, 33870.661293387, 33980.0, 34045.31909361799, 34092.0, 4502.144592527409, 4560.771961401917, 4574.222382195002, 4624.44506659203, 4630.120277147331, 398954.0, 402187.0, 402722.0, 403306.0, 406560.0, 65490.67254663696, 66096.0, 66354.0, 66429.66785166043, 66641.0, 39298.41052384216, 40534.79732601377, 40581.59418405837, 42149.789251054164, 62858.685706571174, 19079.61840763177, 19157.0, 19234.0, 19281.4215573533, 19293.614127717367, 13198.406071726795, 13441.798373024412, 13450.46198152063, 14277.714445711028, 14423.350949207312, 19802.306919257757, 20694.793052069286, 20734.585308294052, 20792.272270470763, 20836.583268334547, 7234.493585449073, 7372.926270737331, 7388.704451821867, 7407.740729074564, 7465.328120469184, 1015.6242190389455, 1122.3995162587976, 1148.538698488681, 1160.6111952496008, 1167.9474423650956, 33807.830960845036, 33853.32293354168, 34375.82812085973, 34377.31245375078, 34521.0, 1172.9003034741972, 1174.1311429542247, 1216.0940099625836, 1271.1102228439945, 1297.6431481342518, 17164.742528862076, 17752.37866674686, 17792.733109003377, 17811.554711132325, 17959.551011224823, 659802.0, 919689.0, 929931.0, 934711.0, 940279.0, 64668.0, 65215.673921630085, 65255.0, 65475.34524654692, 65599.0, 1316.6181807275975, 1419.1272367494123, 1460.4596299369302, 1465.3625672832352, 1492.9178895160653, 31328.530072049394, 34057.829710851285, 34177.0, 34383.93410503992, 35453.29093418066, 52006.21990670219, 52992.20511692251, 53097.734511327195, 53114.734426327625, 53138.73430632977, 441789.0, 449669.0, 450170.0, 450405.0, 452874.0, 27496.450070998068, 29975.400491990473, 30275.243118922204, 30788.0, 30957.0, 50116.24825627545, 52908.0, 53283.4671653294, 53623.46376536185, 55627.721861392056, 44759.0, 44888.77555612201, 44981.1320754717, 45608.77195614133, 45963.310550341106, 43695.78152109219, 44470.0, 45159.54840451685, 45223.547764521936, 45738.54261457343, 44041.559584403745, 44960.550394496946, 45202.09595808, 45276.320855187856, 45513.54486455093, 37315.253694925406, 39223.0, 39527.60472395354, 39661.0, 40199.1960160801, 29605.5559166617, 29710.405791883608, 29823.254418639706, 29932.700672992993, 30056.549151762305], "value": 21832.155268248018, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.6444115825023, 435.8928322061961, 436.3149854728078, 438.0734746012184, 446.7922416076524, 18306.99999999998, 19411.70882436763, 19854.20583176673, 20771.376858694224, 20796.792032079673, 18899.433017009476, 19694.0, 19719.309824156157, 20117.698234526495, 20254.189832406686, 5128.769205385757, 5349.7592608332525, 5384.811531596395, 5393.1370980643105, 5397.7571009304675, 3841.4814000110046, 4230.090530535934, 5270.262163297122, 5347.144456886879, 6586.110875031857, 6413.230412350525, 6536.411722944934, 6572.408483236508, 6597.93402065978, 6622.966885165568, 6190.535709821781, 6427.903581446282, 6428.582142160744, 6432.067350234213, 6531.575447595915, 3728.5898551159353, 5489.3412790465, 5761.769529218826, 5764.106563482667, 5789.507891829199, 14466.566003019927, 14469.20419376934, 14488.927555362261, 14574.854251457456, 14888.851111488962, 2412.3848418653306, 2561.2700380391684, 2600.7919366450637, 2980.882169186555, 3088.934317660426, 10481.371117732884, 10705.1435885129, 10799.352038877692, 10930.726731831688, 10942.945285273603, 1955.501347156466, 2032.9288474903308, 2045.8977051147383, 2076.1591555420077, 2100.716403285552, 87427.56286218592, 90157.09842901553, 91134.08865911323, 91348.0, 91528.54235728845, 11822.822657660143, 11831.526738930346, 11931.403429828473, 11985.161038727205, 12015.399230038463, 17081.743773843402, 17144.0, 17175.31298748036, 17213.22540485681, 17284.740728889075, 8811.603477843513, 8812.823743525221, 8964.282857371394, 9021.639134434548, 9053.411528250643, 8575.485470871768, 8592.914070859211, 8605.655773769104, 8682.218600326001, 8725.21473067427, 5104.259882317043, 5172.793088276502, 5195.350581177353, 5234.2933703949475, 5240.502152295526, 2048.0, 2119.5125121222204, 2136.0, 2142.2502124256384, 2173.5870184664864, 993.7813680990171, 1022.6727447216967, 1054.8681414823147, 1063.1016790811752, 1104.8784633690289, 19727.802721972595, 19842.50393740168, 20062.39812805589, 20227.8988605059, 20397.0, 1016.2733645443543, 1055.377327376836, 1070.4326706845384, 1091.9181061420425, 1115.9442027898572, 10888.401137937331, 11321.830172547418, 11374.829377559343, 11487.310761354345, 11500.194986351042, 389281.0, 554171.0, 555836.0, 564242.0, 571360.0, 91250.0, 95184.52407738056, 100244.0, 103612.48193758982, 105499.0, 992.5187032418953, 1000.8448690452991, 1011.5650270383659, 1014.0873214107362, 1015.1320620869199, 17862.19620117072, 17887.642247155243, 17907.641847163246, 18097.18562664657, 18236.270549178415, 30251.848740756155, 30404.69595304107, 30746.538801918436, 40719.59280407158, 41640.0, 59719.0, 60146.0, 61103.0, 61902.69048654728, 62402.68798655977, 9127.132922372364, 9255.907440925504, 9285.907140928504, 9334.032511798745, 9388.67139650109, 9863.063009014133, 13651.931740341235, 13793.793093103412, 13998.160110393408, 14136.08115472491, 12019.63941081769, 12322.383880806103, 12390.256584604953, 12416.192947458387, 12749.8087528689, 11872.643820685393, 11940.343281119622, 11949.462274197507, 12255.877441225473, 12379.442925068573, 12135.089868260093, 12175.391230438265, 12227.938860305641, 12270.693232669255, 12313.630591081923, 9211.309151813775, 9224.631014759603, 9275.67535136267, 9300.023497532662, 9310.906890931275, 9580.808383832145, 12299.262044277371, 12324.81512777327, 12367.381630918238, 12452.0], "value": 9529.554451007734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.629098288953, 1556.6808804195139, 1599.408218958985, 1783.2866853258704, 1897.0893970893972, 8968.417052891566, 9343.953280233598, 9347.158755711984, 9360.953195234022, 9573.617055317778, 9045.0, 9319.860202096957, 9576.904230957689, 9588.28087893409, 9591.37656052356, 2151.1180416029433, 2412.445137618349, 2487.6392923026197, 2499.7125330587032, 2539.8222124451295, 2516.8741562921873, 2529.190658989124, 2529.557327467695, 2531.253280282312, 2535.784458321036, 2476.158106243877, 2525.532776436365, 2534.581794003987, 2550.0947163756878, 2550.6301586270074, 2099.6745504446835, 2426.478307163968, 2480.1567467061195, 2496.0015993602524, 2503.424212431151, 2417.7461366556436, 2425.211806163004, 2444.1201167579657, 2447.2413551799, 2506.1103308325464, 9498.620055197853, 9555.378900371523, 9565.0, 9565.37825041377, 9566.808663826685, 5609.298837645294, 5732.5987180897355, 5739.598228124033, 5771.624844385102, 5777.393373695785, 3615.403458429356, 3691.427828686558, 3745.831437585315, 3761.0785357587565, 3790.298794722985, 1219.1587804415014, 1240.0823390690914, 1285.6271681212438, 1289.1813698301553, 1353.1001883747297, 13815.171089734547, 14073.859261407357, 14080.859191408159, 14082.436702531886, 14109.435622575085, 5080.898382032338, 5173.663711858717, 5381.246625472437, 5393.5954803389905, 5460.754266058037, 11065.114790816715, 11084.168687348481, 11114.0, 11136.276142050741, 11145.331280123219, 5897.5871688982215, 5910.822675319661, 5912.497437717842, 5917.349091599921, 5920.911186332209, 4706.1528924793865, 5185.351831021122, 5285.524302812689, 5331.120365139786, 5400.081986062379, 4260.595243451867, 4286.314189729628, 4301.09676967834, 4342.6308763754805, 4346.195953748566, 1225.221984040123, 1282.9351638140322, 1288.9548865789652, 1292.8190053392532, 1301.73965206959, 1064.0689396777818, 1066.168388656849, 1085.7882712871071, 1087.516055355375, 1098.373926861697, 6593.406593406617, 6638.6680665966505, 6641.236257830411, 6692.431143352869, 6773.254941956381, 499.79008816297244, 506.4226781469128, 506.89608630230714, 510.32891747352113, 510.5583670125315, 4604.539546045368, 4684.320773487825, 4694.694844835075, 4697.201475749131, 4698.530146985274, 11770.234934729215, 12200.694982625506, 12324.81512777309, 12756.744865102646, 12771.744565108831, 12415.130940834055, 12421.689457763629, 12460.12779105472, 12515.874841251652, 12523.0, 9015.864762028576, 9174.266058715286, 9196.218321442751, 9211.539423028957, 9234.215091717146, 6707.396334329934, 6912.896306555506, 6948.791536253921, 6973.755918542826, 7023.473239506955, 11111.611093611686, 11327.376994265065, 11634.825477618011, 11675.591354302558, 11744.412779361168, 11032.944835275772, 11511.251768635348, 11512.13658975597, 11653.30080195191, 11860.525578976743, 7780.883286750816, 7998.440109192534, 8027.919720802717, 8751.212390884852, 8933.330000250136, 8867.512286824287, 9169.77075573116, 9373.343865929319, 9532.761680957754, 9696.224302055678, 8456.704015359433, 8643.783905402164, 8671.08953559867, 8772.78068048304, 8838.469691818511, 8123.83752324962, 8184.877226841483, 8196.057453392936, 8292.212239837207, 8342.374321925758, 7359.0, 7579.1284002340435, 7581.583012934121, 7701.653425595974, 7735.9613201933635, 5646.88706225865, 5713.514351280188, 5824.621399609012, 5837.67892765902, 5881.500072493886, 5636.661800291996, 5640.520555752642, 5645.096784514458, 5656.23640808502, 5824.213731146241], "value": 4999.72457146996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.6691176470588, 350.8000439749343, 351.53772788782766, 353.6145601294588, 358.66285691450037, 5388.541973932215, 5405.43242959489, 5410.56715462763, 5436.885438485111, 5443.557457273816, 5341.893162136765, 5344.9577332420195, 5356.276902618145, 5363.356397232337, 5416.4312747161475, 4160.459140311756, 4194.853180138696, 4237.131388065439, 4246.893827654302, 4249.150169966019, 4235.025944032859, 4244.363345498169, 4250.97874510627, 4267.914641707164, 4297.818100022503, 4207.611488208884, 4248.851290204843, 4255.659547236229, 4256.212600668886, 4261.211675839964, 4105.712600117978, 4206.936895946563, 4243.936340954888, 4263.335415365396, 4268.253055715253, 3759.3609086455367, 3763.924721505582, 3765.8493660253557, 3770.6606405423645, 3771.4908487354132, 5130.333056702642, 5162.586993040585, 5167.509086636764, 5182.274481572583, 5209.7134657593815, 4556.567126123046, 4833.009233107205, 4854.660173787836, 4886.291487734258, 4901.191303434929, 5584.5811564132855, 5595.356533998602, 5611.551075913917, 5615.820677657656, 5635.63368381058, 4055.2700513907484, 4061.3704875744306, 4073.0, 4075.612816782401, 4093.385992101179, 5716.228309178249, 5827.805299913508, 5832.12518122285, 5865.94134058654, 5873.974902924954, 4167.624913757776, 4175.331946888544, 4185.644220241253, 4193.874183774492, 4206.894827629272, 4253.404523366731, 4260.744355338689, 4261.041265715189, 4268.445102136702, 4281.0, 4748.786304616231, 4839.370881785416, 4842.249451335048, 4865.489123642037, 4873.270968257787, 5560.833175004756, 5587.916181257285, 5608.551315894719, 5621.578381621394, 5631.521320687705, 1407.96514561797, 1436.289036926707, 1450.8113945187058, 1451.4557041109583, 1451.7894905238682, 898.2006014646969, 916.7662246127195, 918.0865039285953, 918.9494577798151, 929.2937367600672, 4933.777979990838, 4967.82612608557, 4972.701637901738, 4976.353074100416, 4983.9252411213865, 5092.312537807423, 5121.692698438105, 5178.766955487012, 5185.481451854785, 5202.739863006835, 4693.647976401783, 4973.875653108629, 4989.201727723546, 5007.674501157486, 5035.899282014412, 2696.8673157273993, 2743.382738883775, 2750.8074434789373, 2793.003002852929, 2799.90200342987, 5294.755732402882, 5383.71192243295, 5390.299261096033, 5403.918941215885, 5426.240326354314, 5055.317532133188, 5085.364329458817, 5233.921491177635, 5235.4502777207845, 5621.887562248814, 5901.232839730807, 6046.0, 6074.362191969868, 6113.144159817629, 6125.356837531995, 5769.278840144982, 5835.67903765297, 5839.445252700986, 5854.326752423526, 5857.4435428634215, 5379.515843574097, 5629.718514074198, 5727.570432217517, 5728.226689396878, 5746.655200687972, 6059.72731227087, 6079.665618390854, 6081.756729730761, 6086.573939824169, 6096.420840020191, 4109.876703698894, 4247.086876321606, 4258.318669013067, 4272.295071313199, 4288.335308027261, 5702.543796496353, 5718.370979192202, 5736.68448235351, 5744.913826292693, 5763.567732420001, 5608.158776183605, 5622.5783066271015, 5648.604597678123, 5658.660480371191, 5673.290838645169, 5417.133258678593, 5551.723103686094, 5576.860578485408, 5590.217369568345, 5592.804251851166, 5487.835364939058, 5508.256385387921, 5513.807016754555, 5548.944510554843, 5563.610547261653, 5597.6641401516035, 5610.4670056344585, 5655.377908430151, 5664.745086471036, 5667.744951477276, 5382.703951282717, 5428.56571474289, 5507.531859791962, 5551.333839939233, 5565.833025009256], "value": 4246.152502012991, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.652166959859, 745.6197339356926, 754.1365136918229, 780.1730166024014, 809.8623234050211, 15594.454194103195, 15879.523614291575, 16793.32826686931, 16919.49241522753, 16927.06901120441, 15398.30707618157, 15549.611259718513, 15556.922215388908, 15576.221188940564, 15594.220288985534, 700.5411455496658, 839.1608391608407, 841.3773807382554, 856.8029353248756, 860.8106216632331, 851.9318454523623, 857.2756021162139, 858.9398742088056, 860.7675927499571, 860.9913900861005, 896.256107430834, 898.1871406377206, 901.4320977783987, 903.1194585279366, 903.327021369081, 749.8500299940007, 881.8897637795276, 892.584947999177, 893.4103491695515, 895.9776005599847, 658.1016911915249, 661.3386613386614, 661.9900701489481, 662.1987395251751, 662.2847324889108, 15335.923320383326, 15339.693206135815, 15370.615734606612, 15385.153816540085, 15392.769108463383, 14142.434302627882, 14497.782533262009, 14499.855001449956, 14538.127712337293, 14624.561263162122, 12664.4301006454, 12952.546660866825, 13147.342632868318, 13185.274809885452, 13409.329533523382, 4626.421697287839, 4665.743384113873, 4702.247640377524, 4756.167670657655, 4867.829625963074, 75882.0, 75894.0, 75926.0, 77249.0, 79195.20804791879, 11319.830202546967, 11550.075993920465, 11565.710857228636, 11616.825747613793, 11621.360825154528, 18653.44039678812, 18676.813231867778, 18753.218444247774, 18758.53103672419, 18801.623967520572, 8656.697015604424, 8705.608247628872, 8723.08407617191, 8726.738197854074, 8926.464412135292, 4898.877528061756, 4907.288443175791, 4921.827736029293, 4971.0, 4975.353204083446, 3538.3454060998797, 3647.8723244686307, 3652.4886515887797, 3653.5433070866143, 3655.469956856241, 591.5740666719955, 647.5628950458422, 669.491186698113, 724.5725022236905, 726.4043484342789, 639.5746828359182, 721.8989341492195, 735.334522257358, 741.499487845702, 790.3084800799301, 10534.157267418741, 10897.618583349546, 10940.12479001678, 11054.778904421866, 11119.555217791198, 684.5345165287653, 696.6481926627021, 702.8980797784293, 703.6938931564683, 746.0860445953664, 4744.667873248908, 4915.926261106087, 4963.5036496350795, 5008.248762685626, 5014.272930425068, 123593.38203309105, 126579.0, 126912.0, 129484.0, 135201.0, 66900.33099669039, 98940.01059989308, 99384.50307748414, 110979.44510277397, 112827.0, 59511.0, 59887.10169347463, 67082.0, 97445.5127724371, 100104.49947750215, 9418.18308756418, 9487.193588544775, 9605.18355939753, 9639.759006024906, 9719.0, 54915.45084549103, 55384.72307638436, 55554.44445555493, 55768.44231557632, 57090.14364784448, 74040.62979685067, 75564.0, 81868.59065704633, 82061.58969205355, 84814.57592711996, 13706.24615646149, 13768.0, 13830.65423364424, 14013.089149205269, 14134.717305654034, 11267.26762760418, 14858.182799946107, 14862.554123376316, 14929.850701492845, 15434.0, 13982.0, 14344.282785860456, 14393.7840932384, 14544.345504452536, 14815.555533334018, 14051.929740351234, 14099.506517271846, 14109.012369134472, 14254.322730752638, 14294.928525357307, 13860.237686927316, 14106.435742570593, 14306.356213970188, 14757.114573125647, 14781.556553302988, 9095.363324567217, 9917.256205784452, 12161.270323780602, 12475.563355282522, 12903.09678322546, 12092.576759813364, 12280.140390172959, 12318.137730358789, 12435.0, 12556.309402982559], "value": 7838.929703716694, "unit": "score"}], "name": "dromaeo_css", "value": 7580.528438420745, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 109.0, 109.0, 110.0, 109.0, 110.0, 111.0, 108.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 126.0, 187.0, 124.0, 124.0, 146.0, 130.0, 157.0, 135.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 160.0, 161.0, 159.0, 159.0, 160.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 116.0, 75.0, 75.0, 75.0, 75.0, 99.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 118.0, 83.0, 85.0, 84.0, 115.0, 84.0, 85.0, 135.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 116.0, 118.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 147.0, 147.0, 145.0, 145.0, 145.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 57.0, 52.0, 56.0, 54.0, 52.0, 59.0, 52.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 78.0, 83.0, 79.0, 78.0, 76.0, 77.0, 101.0, 76.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 126.0, 124.0, 115.0, 121.0, 115.0, 123.0, 142.0, 118.0], "value": 122.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 156.0, 154.0, 158.0, 154.0, 154.0, 155.0, 153.0, 152.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 64.0, 60.0, 60.0, 63.0, 59.0, 58.0, 63.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1475.9999999999998, "alertThreshold": 2.0}]} 04:01:21 INFO - Return code: 0 04:01:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:01:21 ERROR - # TBPL SUCCESS # 04:01:21 INFO - Running post-action listener: _resource_record_post_action 04:01:21 INFO - ##### 04:01:21 INFO - ##### Finished run-tests step (success) 04:01:21 INFO - ##### 04:01:21 INFO - Running post-run listener: _resource_record_post_run 04:01:22 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 496336896; Read time: 12; Write time: 331812 04:01:22 INFO - TinderboxPrint: CPU usage
13.1% 04:01:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:01:22 INFO - TinderboxPrint: I/O write bytes / time
496,336,896 / 331,812 04:01:22 INFO - TinderboxPrint: CPU idle
8,241.5 (86.9%) 04:01:22 INFO - TinderboxPrint: CPU user
1,198.2 (12.6%) 04:01:22 INFO - TinderboxPrint: Swap in / out
0 / 0 04:01:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 249020416; Read time: 0; Write time: 197868 04:01:22 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 247316480; Read time: 12; Write time: 133944 04:01:22 INFO - Running post-run listener: _upload_blobber_files 04:01:22 INFO - Blob upload gear active. 04:01:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:01:22 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 04:01:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:01:22 INFO - (blobuploader) - INFO - Open directory for files ... 04:01:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:01:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:01:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:23 INFO - (blobuploader) - INFO - Done attempting. 04:01:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:01:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:24 INFO - (blobuploader) - INFO - Done attempting. 04:01:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:01:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:01:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:29 INFO - (blobuploader) - INFO - Done attempting. 04:01:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:01:29 INFO - Return code: 0 04:01:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:01:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:01:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86584a73b8986c9c2df67415eefb71ba40b243d75b907c5799f1d4ca3483d52b8713bd78bcf301d9778ee08693beb4915923dbee32f95a594a725c04b38f79a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b9bde9904090e0cfbbdd53fe330476dcba18b8113a7ae52e66bb8d9f6f02a0af9a0ac52379b75e80e1ed77e73657c54a1a28a6756eac0d808712674afcb1f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d90a1719268016dbda7a8f814e61062a620fabbb3468c587a1c307608693c18adefdd231c55a02b8e85c0e577cf71c49acadacc11ff2bfce2726e5dfcdb0f2ef"} 04:01:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:01:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:01:29 INFO - Contents: 04:01:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86584a73b8986c9c2df67415eefb71ba40b243d75b907c5799f1d4ca3483d52b8713bd78bcf301d9778ee08693beb4915923dbee32f95a594a725c04b38f79a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b9bde9904090e0cfbbdd53fe330476dcba18b8113a7ae52e66bb8d9f6f02a0af9a0ac52379b75e80e1ed77e73657c54a1a28a6756eac0d808712674afcb1f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d90a1719268016dbda7a8f814e61062a620fabbb3468c587a1c307608693c18adefdd231c55a02b8e85c0e577cf71c49acadacc11ff2bfce2726e5dfcdb0f2ef"} 04:01:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:01:29 INFO - Copying logs to upload dir... 04:01:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:01:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.052280 ========= master_lag: 0.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-08-01 04:01:28.888845) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:28.896346) ========= 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=41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86584a73b8986c9c2df67415eefb71ba40b243d75b907c5799f1d4ca3483d52b8713bd78bcf301d9778ee08693beb4915923dbee32f95a594a725c04b38f79a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b9bde9904090e0cfbbdd53fe330476dcba18b8113a7ae52e66bb8d9f6f02a0af9a0ac52379b75e80e1ed77e73657c54a1a28a6756eac0d808712674afcb1f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d90a1719268016dbda7a8f814e61062a620fabbb3468c587a1c307608693c18adefdd231c55a02b8e85c0e577cf71c49acadacc11ff2bfce2726e5dfcdb0f2ef"} build_url:https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009920 build_url: 'https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86584a73b8986c9c2df67415eefb71ba40b243d75b907c5799f1d4ca3483d52b8713bd78bcf301d9778ee08693beb4915923dbee32f95a594a725c04b38f79a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b9bde9904090e0cfbbdd53fe330476dcba18b8113a7ae52e66bb8d9f6f02a0af9a0ac52379b75e80e1ed77e73657c54a1a28a6756eac0d808712674afcb1f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d90a1719268016dbda7a8f814e61062a620fabbb3468c587a1c307608693c18adefdd231c55a02b8e85c0e577cf71c49acadacc11ff2bfce2726e5dfcdb0f2ef"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:28.969841) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:28.970213) ========= 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=41bf3e9aed84707956354da8000001c5-1470047826.478723-1678359007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:28.989202) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 04:01:28.989510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 04:01:28.989834) ========= ========= Total master_lag: 0.57 =========