builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1465917685.4 results: success (0) buildid: 20160614053033 builduid: fa9b71cac7904fd4b7e42bf559cb37a1 revision: 5003caf3aa25dad509e53c68d2ee39de58fe5ec5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.400383) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.400825) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.401156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.419624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.420284) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-14 08:21:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-06-14 08:21:25 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121733 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.557996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.558326) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020198 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:25.608422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-14 08:21:25.608791) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5003caf3aa25dad509e53c68d2ee39de58fe5ec5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 5003caf3aa25dad509e53c68d2ee39de58fe5ec5 --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=9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-14 08:21:26,006 truncating revision to first 12 chars 2016-06-14 08:21:26,006 Setting DEBUG logging. 2016-06-14 08:21:26,006 attempt 1/10 2016-06-14 08:21:26,006 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5003caf3aa25?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-14 08:21:26,868 unpacking tar archive at: fx-team-5003caf3aa25/testing/mozharness/ program finished with exit code 0 elapsedTime=1.177285 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-14 08:21:26.803094) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:26.803762) ========= script_repo_revision: 5003caf3aa25dad509e53c68d2ee39de58fe5ec5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:26.804599) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:26.805223) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 08:21:26.817412) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-14 08:21:26.817734) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:21:27 INFO - MultiFileLogger online at 20160614 08:21:27 in /builds/slave/test 08:21:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:21:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:21:27 INFO - {'append_to_log': False, 08:21:27 INFO - 'base_work_dir': '/builds/slave/test', 08:21:27 INFO - 'blob_upload_branch': 'Fx-Team', 08:21:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:21:27 INFO - 'branch': 'Fx-Team', 08:21:27 INFO - 'buildbot_json_path': 'buildprops.json', 08:21:27 INFO - 'config_files': ('talos/linux_config.py',), 08:21:27 INFO - 'default_actions': ('clobber', 08:21:27 INFO - 'read-buildbot-config', 08:21:27 INFO - 'download-and-extract', 08:21:27 INFO - 'populate-webroot', 08:21:27 INFO - 'create-virtualenv', 08:21:27 INFO - 'install', 08:21:27 INFO - 'run-tests'), 08:21:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:21:27 INFO - 'download_minidump_stackwalk': True, 08:21:27 INFO - 'download_symbols': 'ondemand', 08:21:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:21:27 INFO - 'tooltool.py': '/tools/tooltool.py', 08:21:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:21:27 INFO - '/tools/misc-python/virtualenv.py')}, 08:21:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:21:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:21:27 INFO - 'installer_path': 'installer.exe', 08:21:27 INFO - 'log_level': 'info', 08:21:27 INFO - 'log_name': 'talos', 08:21:27 INFO - 'log_to_console': True, 08:21:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:21:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:21:27 INFO - 'opt_config_files': (), 08:21:27 INFO - 'pip_index': False, 08:21:27 INFO - 'sps_profile': False, 08:21:27 INFO - 'sps_profile_interval': 0, 08:21:27 INFO - 'suite': 'dromaeojs', 08:21:27 INFO - 'system_bits': '32', 08:21:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:21:27 INFO - 'title': 'talos-linux64-ix-040', 08:21:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:21:27 INFO - 'use_talos_json': True, 08:21:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:21:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:21:27 INFO - 'work_dir': 'build'} 08:21:27 INFO - ##### 08:21:27 INFO - ##### Running clobber step. 08:21:27 INFO - ##### 08:21:27 INFO - Running pre-action listener: _resource_record_pre_action 08:21:27 INFO - Running main action method: clobber 08:21:27 INFO - rmtree: /builds/slave/test/build 08:21:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:21:27 INFO - Running post-action listener: _resource_record_post_action 08:21:27 INFO - ##### 08:21:27 INFO - ##### Finished clobber step (success) 08:21:27 INFO - ##### 08:21:27 INFO - ##### 08:21:27 INFO - ##### Running read-buildbot-config step. 08:21:27 INFO - ##### 08:21:27 INFO - Running pre-action listener: _resource_record_pre_action 08:21:27 INFO - Running main action method: read_buildbot_config 08:21:27 INFO - Using buildbot properties: 08:21:27 INFO - { 08:21:27 INFO - "project": "", 08:21:27 INFO - "product": "firefox", 08:21:27 INFO - "script_repo_revision": "production", 08:21:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:21:27 INFO - "repository": "", 08:21:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:21:27 INFO - "buildid": "20160614053033", 08:21:27 INFO - "pgo_build": "True", 08:21:27 INFO - "basedir": "/builds/slave/test", 08:21:27 INFO - "buildnumber": 185, 08:21:27 INFO - "slavename": "talos-linux64-ix-040", 08:21:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:21:27 INFO - "platform": "ubuntu64_hw", 08:21:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:21:27 INFO - "branch": "fx-team", 08:21:27 INFO - "repo_path": "integration/fx-team", 08:21:27 INFO - "revision": "5003caf3aa25dad509e53c68d2ee39de58fe5ec5", 08:21:27 INFO - "stage_platform": "linux64-pgo", 08:21:27 INFO - "builduid": "fa9b71cac7904fd4b7e42bf559cb37a1", 08:21:27 INFO - "slavebuilddir": "test" 08:21:27 INFO - } 08:21:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:21:27 INFO - Running post-action listener: _resource_record_post_action 08:21:27 INFO - ##### 08:21:27 INFO - ##### Finished read-buildbot-config step (success) 08:21:27 INFO - ##### 08:21:27 INFO - ##### 08:21:27 INFO - ##### Running download-and-extract step. 08:21:27 INFO - ##### 08:21:27 INFO - Running pre-action listener: _resource_record_pre_action 08:21:27 INFO - Running main action method: download_and_extract 08:21:27 INFO - mkdir: /builds/slave/test/build/tests 08:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:27 INFO - https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:21:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:21:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:21:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:21:28 INFO - Downloaded 1361 bytes. 08:21:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:21:28 INFO - Using the following test package requirements: 08:21:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:21:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:21:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:21:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'jsshell-linux-x86_64.zip'], 08:21:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:21:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:21:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:21:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:21:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:21:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:21:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:21:28 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')]} 08:21:28 INFO - https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:21:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:21:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:21:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:21:29 INFO - Downloaded 24219727 bytes. 08:21:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:21:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:21:30 INFO - Return code: 0 08:21:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:21: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')]} 08:21:30 INFO - https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:21:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:21:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:21:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:21:41 INFO - Downloaded 24219727 bytes. 08:21:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:21:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:21:43 INFO - Return code: 0 08:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:43 INFO - https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:21:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:21:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:21:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:21:45 INFO - Downloaded 14405384 bytes. 08:21:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:21:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:21:46 INFO - Return code: 0 08:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:46 INFO - https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:21:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:21:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:21:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:21:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:21:47 INFO - Downloaded 59341232 bytes. 08:21:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:21:47 INFO - mkdir: /builds/slave/test/properties 08:21:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:21:47 INFO - Writing to file /builds/slave/test/properties/build_url 08:21:47 INFO - Contents: 08:21:47 INFO - build_url:https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:21:48 INFO - Running post-action listener: _resource_record_post_action 08:21:48 INFO - Running post-action listener: set_extra_try_arguments 08:21:48 INFO - ##### 08:21:48 INFO - ##### Finished download-and-extract step (success) 08:21:48 INFO - ##### 08:21:48 INFO - ##### 08:21:48 INFO - ##### Running populate-webroot step. 08:21:48 INFO - ##### 08:21:48 INFO - Running pre-action listener: _resource_record_pre_action 08:21:48 INFO - Running main action method: populate_webroot 08:21:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:21:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:21:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:21:48 INFO - '--tppagecycles', 08:21:48 INFO - '7'], 08:21:48 INFO - 'tests': ['tsvgm']}}, 08:21:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:21:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:21:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:21:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:21:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:21:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:21:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['damp', 'tps']}, 08:21:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'tests': ['damp', 'tps']}, 08:21:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['dromaeo_dom']}, 08:21:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:21:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['basic_compositor_video']}, 08:21:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:21:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['a11yr', 08:21:48 INFO - 'ts_paint', 08:21:48 INFO - 'tpaint', 08:21:48 INFO - 'sessionrestore', 08:21:48 INFO - 'sessionrestore_no_auto_restore', 08:21:48 INFO - 'tabpaint']}, 08:21:48 INFO - 'other-e10s': {'tests': ['a11yr', 08:21:48 INFO - 'ts_paint', 08:21:48 INFO - 'tpaint', 08:21:48 INFO - 'sessionrestore', 08:21:48 INFO - 'sessionrestore_no_auto_restore', 08:21:48 INFO - 'tabpaint']}, 08:21:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:21:48 INFO - 'ts_paint', 08:21:48 INFO - 'tpaint', 08:21:48 INFO - 'sessionrestore', 08:21:48 INFO - 'sessionrestore_no_auto_restore', 08:21:48 INFO - 'tabpaint']}, 08:21:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:21:48 INFO - 'ts_paint', 08:21:48 INFO - 'tpaint', 08:21:48 INFO - 'sessionrestore', 08:21:48 INFO - 'sessionrestore_no_auto_restore', 08:21:48 INFO - 'tabpaint']}, 08:21:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['a11yr', 08:21:48 INFO - 'ts_paint', 08:21:48 INFO - 'tpaint', 08:21:48 INFO - 'sessionrestore', 08:21:48 INFO - 'sessionrestore_no_auto_restore', 08:21:48 INFO - 'tabpaint']}, 08:21:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['a11yr', 08:21:48 INFO - 'ts_paint', 08:21:48 INFO - 'tpaint', 08:21:48 INFO - 'sessionrestore', 08:21:48 INFO - 'sessionrestore_no_auto_restore', 08:21:48 INFO - 'tabpaint']}, 08:21:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['tsvgx', 08:21:48 INFO - 'tsvgr_opacity', 08:21:48 INFO - 'tart', 08:21:48 INFO - 'tscrollx', 08:21:48 INFO - 'cart']}, 08:21:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:21:48 INFO - 'tsvgr_opacity', 08:21:48 INFO - 'tart', 08:21:48 INFO - 'tscrollx', 08:21:48 INFO - 'cart']}, 08:21:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'talos_options': ['--disable-e10s'], 08:21:48 INFO - 'tests': ['tp5o']}, 08:21:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'tests': ['tp5o']}, 08:21:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'talos_options': ['--disable-e10s', 08:21:48 INFO - '--xperf_path', 08:21:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:21:48 INFO - 'tests': ['tp5n']}, 08:21:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:48 INFO - 'talos_options': ['--xperf_path', 08:21:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:21:48 INFO - 'tests': ['tp5n']}}, 08:21:48 INFO - 'talos.zip': {'path': '', 08:21:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:21:48 INFO - Running post-action listener: _resource_record_post_action 08:21:48 INFO - ##### 08:21:48 INFO - ##### Finished populate-webroot step (success) 08:21:48 INFO - ##### 08:21:48 INFO - ##### 08:21:48 INFO - ##### Running create-virtualenv step. 08:21:48 INFO - ##### 08:21:48 INFO - Running pre-action listener: _resource_record_pre_action 08:21:48 INFO - Running main action method: create_virtualenv 08:21:48 INFO - Creating virtualenv /builds/slave/test/build/venv 08:21:48 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 08:21:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:21:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:21:48 INFO - Using real prefix '/usr' 08:21:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:21:48 INFO - Installing distribute.............................................................................................................................................................................................done. 08:21:50 INFO - Installing pip.................done. 08:21:50 INFO - Return code: 0 08:21:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:50 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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:21:50 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 08:21:50 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 08:21:50 INFO - Using env: {'DISPLAY': ':0', 08:21:50 INFO - 'HOME': '/home/cltbld', 08:21:50 INFO - 'LANG': 'en_US.UTF-8', 08:21:50 INFO - 'LANGUAGE': 'en_US:en', 08:21:50 INFO - 'LOGNAME': 'cltbld', 08:21:50 INFO - 'MAIL': '/var/mail/cltbld', 08:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:50 INFO - 'MOZ_NO_REMOTE': '1', 08:21:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:21:50 INFO - 'NO_EM_RESTART': '1', 08:21:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:50 INFO - 'PWD': '/builds/slave/test', 08:21:50 INFO - 'SHELL': '/bin/bash', 08:21:50 INFO - 'SHLVL': '1', 08:21:50 INFO - 'TERM': 'linux', 08:21:50 INFO - 'TMOUT': '86400', 08:21:50 INFO - 'USER': 'cltbld', 08:21:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:50 INFO - '_': '/tools/buildbot/bin/python'} 08:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:21:51 INFO - Cleaning up... 08:21:51 INFO - Return code: 0 08:21:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:21:51 INFO - Using env: {'DISPLAY': ':0', 08:21:51 INFO - 'HOME': '/home/cltbld', 08:21:51 INFO - 'LANG': 'en_US.UTF-8', 08:21:51 INFO - 'LANGUAGE': 'en_US:en', 08:21:51 INFO - 'LOGNAME': 'cltbld', 08:21:51 INFO - 'MAIL': '/var/mail/cltbld', 08:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:51 INFO - 'MOZ_NO_REMOTE': '1', 08:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:21:51 INFO - 'NO_EM_RESTART': '1', 08:21:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:51 INFO - 'PWD': '/builds/slave/test', 08:21:51 INFO - 'SHELL': '/bin/bash', 08:21:51 INFO - 'SHLVL': '1', 08:21:51 INFO - 'TERM': 'linux', 08:21:51 INFO - 'TMOUT': '86400', 08:21:51 INFO - 'USER': 'cltbld', 08:21:51 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:51 INFO - '_': '/tools/buildbot/bin/python'} 08:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:51 INFO - Downloading/unpacking psutil>=3.1.1 08:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:21:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:53 INFO - Installing collected packages: psutil 08:21:53 INFO - Running setup.py install for psutil 08:21:53 INFO - building 'psutil._psutil_linux' extension 08:21:53 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 08:21:53 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 08:21:53 INFO - building 'psutil._psutil_posix' extension 08:21:53 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 08:21:53 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 08:21:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:53 INFO - Successfully installed psutil 08:21:53 INFO - Cleaning up... 08:21:53 INFO - Return code: 0 08:21:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:21:53 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')]} 08:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:53 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')]} 08:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:53 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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:21:53 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 08:21:53 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 08:21:53 INFO - Using env: {'DISPLAY': ':0', 08:21:53 INFO - 'HOME': '/home/cltbld', 08:21:53 INFO - 'LANG': 'en_US.UTF-8', 08:21:53 INFO - 'LANGUAGE': 'en_US:en', 08:21:53 INFO - 'LOGNAME': 'cltbld', 08:21:53 INFO - 'MAIL': '/var/mail/cltbld', 08:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:53 INFO - 'MOZ_NO_REMOTE': '1', 08:21:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:21:53 INFO - 'NO_EM_RESTART': '1', 08:21:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:53 INFO - 'PWD': '/builds/slave/test', 08:21:53 INFO - 'SHELL': '/bin/bash', 08:21:53 INFO - 'SHLVL': '1', 08:21:53 INFO - 'TERM': 'linux', 08:21:53 INFO - 'TMOUT': '86400', 08:21:53 INFO - 'USER': 'cltbld', 08:21:53 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:53 INFO - '_': '/tools/buildbot/bin/python'} 08:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:21:55 INFO - Installing collected packages: mozsystemmonitor 08:21:55 INFO - Running setup.py install for mozsystemmonitor 08:21:56 INFO - Successfully installed mozsystemmonitor 08:21:56 INFO - Cleaning up... 08:21:56 INFO - Return code: 0 08:21:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:21:56 INFO - Using env: {'DISPLAY': ':0', 08:21:56 INFO - 'HOME': '/home/cltbld', 08:21:56 INFO - 'LANG': 'en_US.UTF-8', 08:21:56 INFO - 'LANGUAGE': 'en_US:en', 08:21:56 INFO - 'LOGNAME': 'cltbld', 08:21:56 INFO - 'MAIL': '/var/mail/cltbld', 08:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:56 INFO - 'MOZ_NO_REMOTE': '1', 08:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:21:56 INFO - 'NO_EM_RESTART': '1', 08:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:56 INFO - 'PWD': '/builds/slave/test', 08:21:56 INFO - 'SHELL': '/bin/bash', 08:21:56 INFO - 'SHLVL': '1', 08:21:56 INFO - 'TERM': 'linux', 08:21:56 INFO - 'TMOUT': '86400', 08:21:56 INFO - 'USER': 'cltbld', 08:21:56 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:56 INFO - '_': '/tools/buildbot/bin/python'} 08:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:56 INFO - Downloading/unpacking jsonschema==2.5.1 08:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:21:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 INFO - Downloading functools32-3.2.3-2.tar.gz 08:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:21:58 INFO - warning: no files found matching '*.txt' 08:21:58 INFO - no previously-included directories found matching 'build' 08:21:58 INFO - no previously-included directories found matching 'dist' 08:21:58 INFO - no previously-included directories found matching '.git*' 08:21:58 INFO - Installing collected packages: jsonschema, functools32 08:21:58 INFO - Running setup.py install for functools32 08:21:58 INFO - warning: no files found matching '*.txt' 08:21:58 INFO - no previously-included directories found matching 'build' 08:21:58 INFO - no previously-included directories found matching 'dist' 08:21:58 INFO - no previously-included directories found matching '.git*' 08:21:58 INFO - Successfully installed jsonschema functools32 08:21:58 INFO - Cleaning up... 08:21:58 INFO - Return code: 0 08:21:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:21:58 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')]} 08:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:58 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')]} 08:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:58 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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:21:58 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 08:21:58 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 08:21:58 INFO - Using env: {'DISPLAY': ':0', 08:21:58 INFO - 'HOME': '/home/cltbld', 08:21:58 INFO - 'LANG': 'en_US.UTF-8', 08:21:58 INFO - 'LANGUAGE': 'en_US:en', 08:21:58 INFO - 'LOGNAME': 'cltbld', 08:21:58 INFO - 'MAIL': '/var/mail/cltbld', 08:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:58 INFO - 'MOZ_NO_REMOTE': '1', 08:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:21:58 INFO - 'NO_EM_RESTART': '1', 08:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:58 INFO - 'PWD': '/builds/slave/test', 08:21:58 INFO - 'SHELL': '/bin/bash', 08:21:58 INFO - 'SHLVL': '1', 08:21:58 INFO - 'TERM': 'linux', 08:21:58 INFO - 'TMOUT': '86400', 08:21:58 INFO - 'USER': 'cltbld', 08:21:58 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:58 INFO - '_': '/tools/buildbot/bin/python'} 08:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:58 INFO - Downloading/unpacking blobuploader==1.2.4 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 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 08:21:58 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 08:22:00 INFO - Downloading blobuploader-1.2.4.tar.gz 08:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:22:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:22:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:01 INFO - Downloading docopt-0.6.1.tar.gz 08:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:22:01 INFO - Installing collected packages: blobuploader, requests, docopt 08:22:01 INFO - Running setup.py install for blobuploader 08:22:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:22:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:22:01 INFO - Running setup.py install for requests 08:22:01 INFO - Running setup.py install for docopt 08:22:02 INFO - Successfully installed blobuploader requests docopt 08:22:02 INFO - Cleaning up... 08:22:02 INFO - Return code: 0 08:22:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:02 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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:22:02 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 08:22:02 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 08:22:02 INFO - Using env: {'DISPLAY': ':0', 08:22:02 INFO - 'HOME': '/home/cltbld', 08:22:02 INFO - 'LANG': 'en_US.UTF-8', 08:22:02 INFO - 'LANGUAGE': 'en_US:en', 08:22:02 INFO - 'LOGNAME': 'cltbld', 08:22:02 INFO - 'MAIL': '/var/mail/cltbld', 08:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:02 INFO - 'MOZ_NO_REMOTE': '1', 08:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:02 INFO - 'NO_EM_RESTART': '1', 08:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:02 INFO - 'PWD': '/builds/slave/test', 08:22:02 INFO - 'SHELL': '/bin/bash', 08:22:02 INFO - 'SHLVL': '1', 08:22:02 INFO - 'TERM': 'linux', 08:22:02 INFO - 'TMOUT': '86400', 08:22:02 INFO - 'USER': 'cltbld', 08:22:02 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:02 INFO - '_': '/tools/buildbot/bin/python'} 08:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:22:02 INFO - Running setup.py (path:/tmp/pip-o5ZFsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:22:02 INFO - Running setup.py (path:/tmp/pip-oesdEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:22:02 INFO - Running setup.py (path:/tmp/pip-YWWD3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:22:02 INFO - Running setup.py (path:/tmp/pip-vwcZNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:22:02 INFO - Running setup.py (path:/tmp/pip-UA6lB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:22:02 INFO - Running setup.py (path:/tmp/pip-NakVMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:22:02 INFO - Running setup.py (path:/tmp/pip-0bhfKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:22:02 INFO - Running setup.py (path:/tmp/pip-d4oLV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:22:02 INFO - Running setup.py (path:/tmp/pip-HvHUTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:22:02 INFO - Running setup.py (path:/tmp/pip-LiOyqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:22:03 INFO - Running setup.py (path:/tmp/pip-f6QA4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:22:03 INFO - Running setup.py (path:/tmp/pip-B0RPOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:22:03 INFO - Running setup.py (path:/tmp/pip-YpzVUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:22:03 INFO - Running setup.py (path:/tmp/pip-8RpzzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:03 INFO - Running setup.py (path:/tmp/pip-_uSZS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:22:03 INFO - Running setup.py (path:/tmp/pip-zj1ZdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:22:03 INFO - Running setup.py (path:/tmp/pip-F4bkDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:22:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:22:03 INFO - Running setup.py install for manifestparser 08:22:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:22:03 INFO - Running setup.py install for mozcrash 08:22:03 INFO - Running setup.py install for mozdebug 08:22:03 INFO - Running setup.py install for mozdevice 08:22:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:22:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for mozfile 08:22:04 INFO - Running setup.py install for mozhttpd 08:22:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for mozinfo 08:22:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for mozInstall 08:22:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for mozleak 08:22:04 INFO - Running setup.py install for mozlog 08:22:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for moznetwork 08:22:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for mozprocess 08:22:04 INFO - Running setup.py install for mozprofile 08:22:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:22:04 INFO - Running setup.py install for mozrunner 08:22:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:22:05 INFO - Running setup.py install for mozscreenshot 08:22:05 INFO - Running setup.py install for moztest 08:22:05 INFO - Running setup.py install for mozversion 08:22:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:22:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:22:05 INFO - Cleaning up... 08:22:05 INFO - Return code: 0 08:22:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22: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')]} 08:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22: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')]} 08:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:05 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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:22:05 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 08:22:05 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 08:22:05 INFO - Using env: {'DISPLAY': ':0', 08:22:05 INFO - 'HOME': '/home/cltbld', 08:22:05 INFO - 'LANG': 'en_US.UTF-8', 08:22:05 INFO - 'LANGUAGE': 'en_US:en', 08:22:05 INFO - 'LOGNAME': 'cltbld', 08:22:05 INFO - 'MAIL': '/var/mail/cltbld', 08:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:05 INFO - 'MOZ_NO_REMOTE': '1', 08:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:05 INFO - 'NO_EM_RESTART': '1', 08:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:05 INFO - 'PWD': '/builds/slave/test', 08:22:05 INFO - 'SHELL': '/bin/bash', 08:22:05 INFO - 'SHLVL': '1', 08:22:05 INFO - 'TERM': 'linux', 08:22:05 INFO - 'TMOUT': '86400', 08:22:05 INFO - 'USER': 'cltbld', 08:22:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:05 INFO - '_': '/tools/buildbot/bin/python'} 08:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:22:05 INFO - Running setup.py (path:/tmp/pip-XylLdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:22:05 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)) 08:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:22:05 INFO - Running setup.py (path:/tmp/pip-QIDGf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:22:05 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)) 08:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:22:05 INFO - Running setup.py (path:/tmp/pip-y8JyDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:22:05 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)) 08:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:22:05 INFO - Running setup.py (path:/tmp/pip-XZlg_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:22:05 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)) 08:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:22:05 INFO - Running setup.py (path:/tmp/pip-EPas5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:22:05 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)) 08:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:22:05 INFO - Running setup.py (path:/tmp/pip-67APnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:22:06 INFO - Running setup.py (path:/tmp/pip-WsWkYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:22:06 INFO - Running setup.py (path:/tmp/pip-SHhQ_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:22:06 INFO - Running setup.py (path:/tmp/pip-yKella-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:22:06 INFO - Running setup.py (path:/tmp/pip-fLa9mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:22:06 INFO - Running setup.py (path:/tmp/pip-1OlHsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:22:06 INFO - Running setup.py (path:/tmp/pip-fRKInf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:22:06 INFO - Running setup.py (path:/tmp/pip-LhbcK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:22:06 INFO - Running setup.py (path:/tmp/pip-QbUc42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:06 INFO - Running setup.py (path:/tmp/pip-bDdaE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:22:06 INFO - Running setup.py (path:/tmp/pip-b8CDwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:22:06 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)) 08:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:22:06 INFO - Running setup.py (path:/tmp/pip-QRR_lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:22:06 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)) 08:22:06 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)) 08:22:06 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)) 08:22:06 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)) 08:22:06 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)) 08:22:06 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)) 08:22:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:08 INFO - Downloading blessings-1.6.tar.gz 08:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:22:08 INFO - Installing collected packages: blessings 08:22:08 INFO - Running setup.py install for blessings 08:22:08 INFO - Successfully installed blessings 08:22:08 INFO - Cleaning up... 08:22:08 INFO - Return code: 0 08:22:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:22:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:09 INFO - Reading from file tmpfile_stdout 08:22:09 INFO - Current package versions: 08:22:09 INFO - argparse == 1.2.1 08:22:09 INFO - blessings == 1.6 08:22:09 INFO - blobuploader == 1.2.4 08:22:09 INFO - docopt == 0.6.1 08:22:09 INFO - functools32 == 3.2.3-2 08:22:09 INFO - jsonschema == 2.5.1 08:22:09 INFO - manifestparser == 1.1 08:22:09 INFO - mozInstall == 1.12 08:22:09 INFO - mozcrash == 0.17 08:22:09 INFO - mozdebug == 0.1 08:22:09 INFO - mozdevice == 0.48 08:22:09 INFO - mozfile == 1.2 08:22:09 INFO - mozhttpd == 0.7 08:22:09 INFO - mozinfo == 0.9 08:22:09 INFO - mozleak == 0.1 08:22:09 INFO - mozlog == 3.2 08:22:09 INFO - moznetwork == 0.27 08:22:09 INFO - mozprocess == 0.22 08:22:09 INFO - mozprofile == 0.28 08:22:09 INFO - mozrunner == 6.11 08:22:09 INFO - mozscreenshot == 0.1 08:22:09 INFO - mozsystemmonitor == 0.3 08:22:09 INFO - moztest == 0.7 08:22:09 INFO - mozversion == 1.4 08:22:09 INFO - psutil == 3.1.1 08:22:09 INFO - requests == 1.2.3 08:22:09 INFO - wsgiref == 0.1.2 08:22:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:09 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 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:22:09 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 08:22:09 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 08:22:09 INFO - Using env: {'DISPLAY': ':0', 08:22:09 INFO - 'HOME': '/home/cltbld', 08:22:09 INFO - 'LANG': 'en_US.UTF-8', 08:22:09 INFO - 'LANGUAGE': 'en_US:en', 08:22:09 INFO - 'LOGNAME': 'cltbld', 08:22:09 INFO - 'MAIL': '/var/mail/cltbld', 08:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:09 INFO - 'MOZ_NO_REMOTE': '1', 08:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:09 INFO - 'NO_EM_RESTART': '1', 08:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:09 INFO - 'PWD': '/builds/slave/test', 08:22:09 INFO - 'SHELL': '/bin/bash', 08:22:09 INFO - 'SHLVL': '1', 08:22:09 INFO - 'TERM': 'linux', 08:22:09 INFO - 'TMOUT': '86400', 08:22:09 INFO - 'USER': 'cltbld', 08:22:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:09 INFO - '_': '/tools/buildbot/bin/python'} 08:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 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)) 08:22:09 INFO - Cleaning up... 08:22:09 INFO - Return code: 0 08:22:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:22:09 INFO - Using env: {'DISPLAY': ':0', 08:22:09 INFO - 'HOME': '/home/cltbld', 08:22:09 INFO - 'LANG': 'en_US.UTF-8', 08:22:09 INFO - 'LANGUAGE': 'en_US:en', 08:22:09 INFO - 'LOGNAME': 'cltbld', 08:22:09 INFO - 'MAIL': '/var/mail/cltbld', 08:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:09 INFO - 'MOZ_NO_REMOTE': '1', 08:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:09 INFO - 'NO_EM_RESTART': '1', 08:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:09 INFO - 'PWD': '/builds/slave/test', 08:22:09 INFO - 'SHELL': '/bin/bash', 08:22:09 INFO - 'SHLVL': '1', 08:22:09 INFO - 'TERM': 'linux', 08:22:09 INFO - 'TMOUT': '86400', 08:22:09 INFO - 'USER': 'cltbld', 08:22:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:09 INFO - '_': '/tools/buildbot/bin/python'} 08:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:22:09 INFO - Cleaning up... 08:22:09 INFO - Return code: 0 08:22:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f408cc07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', '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 08:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:22:09 INFO - Using env: {'DISPLAY': ':0', 08:22:09 INFO - 'HOME': '/home/cltbld', 08:22:09 INFO - 'LANG': 'en_US.UTF-8', 08:22:09 INFO - 'LANGUAGE': 'en_US:en', 08:22:09 INFO - 'LOGNAME': 'cltbld', 08:22:09 INFO - 'MAIL': '/var/mail/cltbld', 08:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:09 INFO - 'MOZ_NO_REMOTE': '1', 08:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:09 INFO - 'NO_EM_RESTART': '1', 08:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:09 INFO - 'PWD': '/builds/slave/test', 08:22:09 INFO - 'SHELL': '/bin/bash', 08:22:09 INFO - 'SHLVL': '1', 08:22:09 INFO - 'TERM': 'linux', 08:22:09 INFO - 'TMOUT': '86400', 08:22:09 INFO - 'USER': 'cltbld', 08:22:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:09 INFO - '_': '/tools/buildbot/bin/python'} 08:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:09 INFO - Downloading/unpacking flake8 08:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:22:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:22:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:22:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:22:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:22:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:22:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:22:12 INFO - no previously-included directories found matching 'docs/_build' 08:22:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:22:12 INFO - Running setup.py install for pep8 08:22:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:22:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:22:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:22:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:22:12 INFO - no previously-included directories found matching 'docs/_build' 08:22:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:22:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:22:12 INFO - Cleaning up... 08:22:12 INFO - Return code: 0 08:22:12 INFO - Running post-action listener: _resource_record_post_action 08:22:12 INFO - Running post-action listener: _start_resource_monitoring 08:22:12 INFO - Starting resource monitoring. 08:22:12 INFO - ##### 08:22:12 INFO - ##### Finished create-virtualenv step (success) 08:22:12 INFO - ##### 08:22:12 INFO - ##### 08:22:12 INFO - ##### Running install step. 08:22:12 INFO - ##### 08:22:12 INFO - Running pre-action listener: _resource_record_pre_action 08:22:12 INFO - Running main action method: install 08:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:12 INFO - Reading from file tmpfile_stdout 08:22:12 INFO - Detecting whether we're running mozinstall >=1.0... 08:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:22:12 INFO - Reading from file tmpfile_stdout 08:22:12 INFO - Output received: 08:22:12 INFO - Usage: mozinstall [options] installer 08:22:12 INFO - Options: 08:22:12 INFO - -h, --help show this help message and exit 08:22:12 INFO - -d DEST, --destination=DEST 08:22:12 INFO - Directory to install application into. [default: 08:22:12 INFO - "/builds/slave/test"] 08:22:12 INFO - --app=APP Application being installed. [default: firefox] 08:22:12 INFO - mkdir: /builds/slave/test/build/application 08:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:22:29 INFO - Reading from file tmpfile_stdout 08:22:29 INFO - Output received: 08:22:29 INFO - /builds/slave/test/build/application/firefox/firefox 08:22:29 INFO - Running post-action listener: _resource_record_post_action 08:22:29 INFO - ##### 08:22:29 INFO - ##### Finished install step (success) 08:22:29 INFO - ##### 08:22:29 INFO - ##### 08:22:29 INFO - ##### Running run-tests step. 08:22:29 INFO - ##### 08:22:29 INFO - Running pre-action listener: _resource_record_pre_action 08:22:29 INFO - Running main action method: run_tests 08:22:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:22:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:22:29 INFO - Python 2.7.3 08:22:29 INFO - Return code: 0 08:22:29 INFO - grabbing minidump binary from tooltool 08:22: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')]} 08:22:29 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 0x24bf1c0>, '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 0x24c00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:22:29 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 08:22:29 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 08:22:29 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 08:22:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:22:29 INFO - Return code: 0 08:22:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:22:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:22:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:22:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:22:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:22:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:22:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:22:30 INFO - Return code: 0 08:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 08:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 08:22:30 INFO - Using env: {'DISPLAY': ':0', 08:22:30 INFO - 'HOME': '/home/cltbld', 08:22:30 INFO - 'LANG': 'en_US.UTF-8', 08:22:30 INFO - 'LANGUAGE': 'en_US:en', 08:22:30 INFO - 'LOGNAME': 'cltbld', 08:22:30 INFO - 'MAIL': '/var/mail/cltbld', 08:22:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:22:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:30 INFO - 'MOZ_NO_REMOTE': '1', 08:22:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:30 INFO - 'NO_EM_RESTART': '1', 08:22:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:30 INFO - 'PWD': '/builds/slave/test', 08:22:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:22:30 INFO - 'SHELL': '/bin/bash', 08:22:30 INFO - 'SHLVL': '1', 08:22:30 INFO - 'TERM': 'linux', 08:22:30 INFO - 'TMOUT': '86400', 08:22:30 INFO - 'USER': 'cltbld', 08:22:30 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396', 08:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:30 INFO - '_': '/tools/buildbot/bin/python'} 08:22:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 08:22:31 INFO - mozversion application_buildid: 20160614053033 08:22:31 INFO - mozversion application_changeset: 5003caf3aa25dad509e53c68d2ee39de58fe5ec5 08:22:31 INFO - mozversion application_display_name: Nightly 08:22:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:22:31 INFO - mozversion application_name: Firefox 08:22:31 INFO - mozversion application_remotingname: firefox 08:22:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:22:31 INFO - mozversion application_vendor: Mozilla 08:22:31 INFO - mozversion application_version: 50.0a1 08:22:31 INFO - mozversion platform_buildid: 20160614053033 08:22:31 INFO - mozversion platform_changeset: 5003caf3aa25dad509e53c68d2ee39de58fe5ec5 08:22:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:22:31 INFO - mozversion platform_version: 50.0a1 08:22:31 INFO - using testdate: 1465917751 08:22:31 INFO - actual date: 1465917751 08:22:31 INFO - starting webserver on 'localhost:60800' 08:22:31 INFO - SUITE-START | Running 2 tests 08:22:31 INFO - TEST-START | dromaeo_css 08:22:31 INFO - operating with platform_type : linux_ 08:22:31 INFO - Initialising browser for dromaeo_css test... 08:22:31 INFO - TEST-INFO | started process 2923 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe0vcyI/profile http://localhost:60800/getInfo.html) 08:22:35 INFO - PROCESS | 2923 | __metrics Screen width/height:1600/1200 08:22:35 INFO - PROCESS | 2923 | colorDepth:24 08:22:35 INFO - PROCESS | 2923 | Browser inner width/height: 1024/697 08:22:35 INFO - PROCESS | 2923 | __metrics 08:22:36 INFO - PROCESS | 2923 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:22:36 INFO - PROCESS | 2923 | console.error: PushService: 08:22:36 INFO - PROCESS | 2923 | stateChangeProcessEnqueue: Error transitioning state 08:22:36 INFO - PROCESS | 2923 | UnknownError 08:22:37 INFO - TEST-INFO | 2923: exit 0 08:22:37 INFO - Browser initialized. 08:22:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:22:37 INFO - TEST-INFO | started process 3003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe0vcyI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:49 INFO - PROCESS | 3003 | RSS: Main: 174235648 08:22:49 INFO - PROCESS | 3003 | 08:23:40 INFO - PROCESS | 3003 | 1465917820836 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] 08:25:48 INFO - PROCESS | 3003 | Cycle 1(1): loaded http://localhost:60800/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60800/tests/dromaeo/cssquery-ext.html) 08:25:49 INFO - PROCESS | 3003 | RSS: Main: 272617472 08:25:49 INFO - PROCESS | 3003 | 08:28:52 INFO - PROCESS | 3003 | Cycle 1(1): loaded http://localhost:60800/tests/dromaeo/cssquery-ext.html (next: http://localhost:60800/tests/dromaeo/cssquery-jquery.html) 08:28:52 INFO - PROCESS | 3003 | RSS: Main: 263442432 08:28:52 INFO - PROCESS | 3003 | 08:31:54 INFO - PROCESS | 3003 | Cycle 1(1): loaded http://localhost:60800/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60800/tests/dromaeo/cssquery-mootools.html) 08:31:55 INFO - PROCESS | 3003 | RSS: Main: 263131136 08:31:55 INFO - PROCESS | 3003 | 08:34:57 INFO - PROCESS | 3003 | Cycle 1(1): loaded http://localhost:60800/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60800/tests/dromaeo/cssquery-prototype.html) 08:34:58 INFO - PROCESS | 3003 | RSS: Main: 259760128 08:34:58 INFO - PROCESS | 3003 | 08:38:00 INFO - PROCESS | 3003 | Cycle 1(1): loaded http://localhost:60800/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60800/tests/dromaeo/cssquery-yui.html) 08:38:01 INFO - PROCESS | 3003 | RSS: Main: 248442880 08:38:01 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | Cycle 1(1): loaded http://localhost:60800/tests/dromaeo/cssquery-yui.html (next: http://localhost:60800/tests/dromaeo/cssquery-dojo.html) 08:41:04 INFO - PROCESS | 3003 | RSS: Main: 320831488 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | __start_tp_report 08:41:04 INFO - PROCESS | 3003 | _x_x_mozilla_page_load 08:41:04 INFO - PROCESS | 3003 | _x_x_mozilla_page_load_details 08:41:04 INFO - PROCESS | 3003 | |i|pagename|runs| 08:41:04 INFO - PROCESS | 3003 | |0;dojo.html;3107.7979931304476;3114.937701245975;3134.263448089699;3145.5910731604886;3172.349668317995;5318.76065577049;5367.141257398817;5407.702576358303;5489.011977843987;5510.283663123793;5352.143657014869;5401.297831281938;5414.458554144584;5425.430329815362;5481;4800.039992001596;4876.2929375240565;4891.682040667363;4891.730954797485;5034.1945288753805;4950.4307004694565;4953.034158339107;5000.274960130779;5007.198848184291;5020.472850350716;4794.83218087367;4830.444498882621;4838.201696720037;4851.975740121312;4888.6577939543895;4846.6365022623095;4864.318995340655;4935;4938.678985865908;4970.801167953277;4887.364642596475;4895.804167833282;4901.2648102784515;4905.043516514245;4967.453580106186;5304;5334.306540149794;5355.812546560852;5364.141737322048;5483.150111732688;5811.709414529257;5835.649861008311;5849.678267695275;5860.736266868002;5865;5692.743826527816;5915.231019967419;5942.524598032147;5946.256717910262;5946.583739138262;5404.864878378033;5444.8094316698725;5447.863803404907;5471.4528547145765;5525;5928.347881733006;5948.791792287249;5998.790042348497;5999.400059993965;6009.338972712998;5550;5706.743196556165;5749.741261643236;5775.075987841925;5783.219265399117;5768.682722450306;5861.443162899518;5915.733791979371;5918.053111502139;5919.230500034968;5784.2769653793275;5826.70866456684;5830.38780928005;5837.29952405714;5857.765689372377;5618.213450116987;5761.049426844524;5824.980628390015;5860.736266868002;5861.472467477947;4478.865634030984;4479.17135329965;4485.506594274627;4513.977430112894;4527.004059106991;3966.286068507696;3990.9822995136055;3996.7002474814503;4020.296448121567;4031.6169963853386;5339.813106541253;5341.145416733304;5387.595930305243;5393.7572809223675;5485.232067510552;5743.994800909824;5749.310082790008;5751.741171647286;5759.596828221985;5815.098659707752;5402.702851343214;5413.621046526705;5421.322334708161;5423.457654234624;5427.972860135674;4829.95170048295;4836.588889944394;4885.169521181408;4887.853364399074;4888.706677599355;5960.7019649017375;5969.522438204933;5975.282966044103;5976.073708575178;5978.1929439525975;5717.771289148387;5812.5349972001295;5816;5830.62100963436;5830.7959221427045;5890.764369425175;5898.233229680114;5947.137665038459;5954.940450595612;5998.7300571473515;5664.291963504562;5665.3201615806365;5725.227094342377;5727.513161381163;5728.713564321851;5681.233033540584;5769.711514424178;5829.213056237354;5830.2129212555765;5831.854203644943;5590.468905453975;5627.774889004558;5628.746706398304;5678.403767604342;5680.943190568041;5552.083906155439;5624.606277560531;5672.2342483765815;5673.262475878109;5674.4892959633835;5466.3986961435;5508.614396992172;5539.556835453234;5550.722463876871;5562.888742225214;5666.121751128581;5763.8559036024435;5779.595428320145;5787.884242315046;5801.94198058014;5529.861753456034;5552.139418390156;5552.916706249491;5557.694326811902;5565.109582466948 08:41:04 INFO - PROCESS | 3003 | |1;ext.html;598.1804927249666;610.667186383421;617.9876402471948;633.6419922743646;643.0803950350997;32551.348973020555;35378.64621353785;35403.2919341613;35446;43604;42954.57045429545;46290;46568.06863862721;46624.76687616565;46660.53339466605;9031.096890310966;9205.125513076247;9207.677731279407;9208.953955230214;9211.677591284306;8982.595783189772;9074.455532668027;9077.36458447909;9080.455172689626;9086.227670648002;9155.176034156924;9186.127317904788;9193.172614464696;9193.264538836911;9196.678116265932;9084.545772711372;9114;9123.543822808833;9140.588673509676;9140.817183656323;9069.410488318304;9133.954330228373;9134.269258459308;9136.725898222998;9145.679901203492;41919.37120943188;42038;42190.578094218974;42299;42413.57586424127;3625.329314076904;3814.7901865397394;3886.8250928707994;4000.179963107556;4144.772037537934;33246.6675333246;33316.33367332664;33380.33239335224;33719.66280337214;34809.825950870334;4405;4406.823727050914;4462.486814016397;4573.336866154422;4617.7691115444095;342239;343222;343365;344529;345236;35786;58148.418515815145;58782;58884.705576471846;58968.705156473945;36488;38201.426978595344;38422.423663645066;38460.61539384626;38536.61463385386;13405.932970335087;13470.93264533671;13503.459861605426;13510.864891351157;13543.322833858268;12946.935265323613;13030.153040052366;13035.15271507349;13036.478540858448;13050.738985220243;8984.910150898408;8986.775330616787;9024.684136055206;9081.364304498622;9094.954525227331;3429.3141371725756;3487.093355727529;3523.612402635708;3546.964530354715;3548.416099739282;1032.6901929421144;1033.7467320506523;1056.6671498477954;1058.862347894784;1114.0753174864842;32771.83614081914;32805.507917381256;33103.3379332412;33227.50158747621;33378.3324333512;1098.5825386353135;1118.78743038824;1150.8906653867868;1187.1749134351714;1196.4257156564897;16443.917780411022;17118.743218851727;17363.82636173622;17385.000724916797;17478.737818932725;501522;505370;506763;506851;508869;59737.701311494035;60137.699311503166;60229;60230.69884650549;60266.69866650726;1253.254313683357;1313.2317594207304;1337.1776357540043;1369.2948131712176;1378.5175188684082;29329.266768330963;30055.398892022473;30388.73480687746;30447;30749.8462507686;50346.74826625844;50433.49566504288;50746.492535074176;50863.74568127136;50900.23649645184;369325;373087;373112;373968;374360;26056.73943260543;28677.85661071681;29364.41271174522;29509.26226844346;31141;48074;48744.2688359682;48912.266316006004;49962.50037499578;49984.75007624939;41211.38182927318;41475.01824033267;41847.79076104722;42550.14899702051;42839.78580107079;41165.382519261635;41238.79380603078;41493.79253103836;41626.58373416227;41772.58227417687;38379.616203837606;40973.59026409698;42001.579984199765;42126.1574768509;42165.57834421739;34879.82560087183;37014.62985370112;37090;37330.6266937338;37763.24473510569;26429.735702642727;26584.601230981938;26691.466170676867;26741.8662906692;26977 08:41:04 INFO - PROCESS | 3003 | |2;jquery.html;409.9549049604543;410.89727568107975;412.4450820407064;419.5217452104601;427.8438369994953;18145.909270453645;18923.81076189238;19787.703184452246;19791.703124453128;19954.501137471536;12634.873651263484;12925.806112908314;12926.384201974754;12982.740345193091;13258;1745.7119575270128;1761.462753860072;1956.9706454403195;5335.8132465363815;5517.393086760453;5245.527902488775;5335.759890804923;5688.658680479164;5983.222181116449;6021.277446706401;6019.157317975487;6060.575759696823;6073.089036644516;6084.5132389408745;6115.0521669141135;6030.643105301294;6462.353764623524;6541.378569035958;6556.246031706367;6563.54055216135;5732.283464566929;5758.683272420015;5793.507551858097;5793.768249270024;5808.244928159313;13730.519431819937;14016.509422170175;14081.929590352085;14109.929450352785;14290.64273393163;2283.9836272858583;2289.759575244592;2310.353101131686;2367.124164059292;2536.759007894247;10283.177345812392;10298.691039268833;10401.479926003745;10892.509837057352;11015.284006539548;1947.2892394275987;1991.3428568572335;2042.1218875883364;2052.8768273903615;2109.683547467877;59851.70074149645;61415.692921535105;72300.63849680718;84852.72720909253;85754;10458.581656733646;11371.658850234506;11391.829122563167;11392.772144557062;11524.193306468467;14884.255787210595;14931.925340373446;15005.774913376308;15016.324265408082;15057.096574205583;6674.666266686646;6959.617221052789;8038.758837234892;8246.628901699438;8369.95815020921;7982.361411087099;7994.680212791423;8044.597770111471;8167.959160204161;8273;5023.79904803811;5074;5101.158308879068;5140.691558506502;5234.555062819703;1984.9503762406055;2044.5604195098126;2051.9487012824798;2086.926957556478;2095.4447071526056;908.2416182487697;941.7080704981478;972.2659392159017;1009.9646512372178;1056.799208150449;19514.12186451613;19807.504812379804;19861;19869.801301987085;20144.697829532568;932.1517419148543;957.1433566957674;1015.4059874973076;1036.0778906772978;1114.2534501883758;9231.76920576991;10747.623833165802;10962.287451315638;10984.121270298358;11237.943810280896;426447;429649;441561;442104;442829;99187;99476;99741.50129249308;100566;101846;991.8462638291077;1050.669039252633;1061.2412125330272;1068.56188962525;1076.445630500293;17739.556511087325;17850.642987139923;18217.45347639573;18318.81681183171;18621.348252811087;40834.59165408308;44212;45514.54485455103;45852.77073614611;46655.53344466512;51204.74397627988;60431.093533596155;61770.38229617646;62744.372556273855;63357.14892829926;7633.465657404151;8001.919980800118;8159;9071;9163.86254206174;9708.6116555337;13788.379522921292;13890.513832015831;14434.206118663154;14620.488282910048;11473.598424055119;11963.162578619413;12256.693582660504;12379.071569632493;12522.74954500923;11549.826752598885;12235.816462753244;12292.631221063382;12543.87456125427;12858.614241572768;12170.452329645364;12213.022958163503;12512.43694033789;12529.749405011666;12640.6839829005;7803.0636323639255;8030.23712747288;8368.497890126611;8596.527191004305;8733.126687331216;5090.541851233407;5152.175651895679;11141.919843091197;11610.535578576762;12071.637850864488 08:41:04 INFO - PROCESS | 3003 | |3;mootools.html;1361.0540674231413;1456.7159403916235;1497.6854860479298;1639.1476432255229;1805.512511621863;8946.686865959693;9359.298052646045;9389.718308450749;9688.854667179998;9695.030496950301;9422.952885235563;9642.180414664745;9644.083812037863;9647.179989700866;9649;2184.56308738253;2384.4277373430336;2456.4472993576483;2541.237628711393;2570.588705807071;2520.332111990324;2531.999860055277;2532.430203204283;2532.7973762098986;2538.225841118458;2468.3458883395915;2503.662005629235;2504.499100179962;2505.749425057498;2505.799536037122;2121.586290673319;2381.6546600742968;2453.1659235859806;2459.8155138364696;2463.7043554773363;2396.6405039244255;2406.867622280774;2421.2252079334444;2422.2854257993936;2430.5503481855912;9519.143277105093;9526.142647161789;9573.617055317778;9592.08875156859;9606.519674016341;5569.470900264469;5702.543796496312;5716.885662286731;5718.857028574277;5719.14212868069;3622.836972336251;3680.1535646801117;3729.0490924814;3766.8493260269556;3802.657760801541;1151.5739176504662;1176.9352685602373;1201.34526682958;1201.9218750781172;1202.5790973159412;13448.596542103753;13450.798238026437;13465.528706495325;13469.797953030713;13476.797848032287;4981.900361992813;5039.97480012605;5076.82231121909;5115.232715092766;5121.743912804345;11050.668479945614;11089.55641774336;11098.889011109946;11153.386563739072;11166.274192177483;5692.544596432275;5729.742161602738;5735.8566035849435;5739.311282646109;5753.88492230153;4603.125406172817;5009.398872135367;5152.510511501477;5153.845384638467;5249.107651699221;4157.459530261047;4182.728122672017;4248.553901840324;4271.700980931367;4307.332363483665;1351.094766506443;1353.810466534686;1368.0286996232787;1375.9724805503834;1376.3187222325014;822.9753107406667;848.8005318750089;923.5151545438564;941.3645789092335;966.1932286540676;6231.532635052389;6276.623402595859;6355.3644635536075;6414.8717025659225;6426.582272152295;997.5311603546321;1001.6844693921391;1010.7877345757327;1030.922680798928;1031.159604922001;4193.287141185945;4276.700630955803;4297.763623000765;4308.547602501755;4308.870733877989;11744.765104697859;12161.027117830728;12200.877991219973;12224.144309898222;12289.69275768095;11224.158188135922;11673.182877198515;11680.532778688927;12106.273623582612;12136.817947730791;8706.216440520384;8775.473471591726;8799.076097009849;8802.823943521094;8805;6117.174181485442;6335.524835637438;6392.776252831129;6428.967855160694;6552.180977377828;10815.621453249098;11033.282836615594;11133.554657813596;11284;11294.378809165575;11302.208845380745;11408.82886756716;11425.828612570984;11440.48517816717;11447.141464390037;8191.713290034821;8208.83582328362;8322.958385208036;8444.957775211331;8468.110848360833;9408.482533460725;9423.151916327564;9455.385399948982;9506.191973682315;9509.334346595948;7726.536407815549;7955.164707705608;8099.757007289791;8121.79695507617;8391.202835730595;7975.441719079608;8092.018666985554;8137;8157.592120394075;8228.62971166287;7263;7277.963610181915;7344.045274114331;7352.632368381666;7367.1527774304495;5312.229726689686;5312.442193569619;5312.468753124811;5320.52115309624;5403.567714582902;5074.289599456153;5124.205748109049;5125;5193;5234.607404444758 08:41:04 INFO - PROCESS | 3003 | |4;prototype.html;324.7269116186693;343.6975461593797;344.1224876564761;345.49557645837075;351.2588438394987;5222.895542089156;5237.816676416326;5363.4368391318885;5397.8948210198005;5410.729463526823;5259.86850328742;5283.682979021261;5296.364436267643;5313.814016509423;5327.188755823019;4123.731957422773;4153.813078411479;4179.3939878717565;4183.769892655918;4191.203671302443;3915.6867450604027;3996.6802655787615;4155;4180.728252663583;4186.434831297782;4044.6359827615656;4088.5093788745394;4117.197146556423;4128.174365126972;4131.21506913687;4096.361455417827;4104.876853694364;4105.261053010457;4110;4112.218678451115;3430.5711786026745;3663.358912190356;3679.521662183899;3687.668109870098;3688.114852435423;5164.328637277167;5191.974040129775;5195.558377537914;5198.506141916512;5222.608304377149;4722.456917454468;4730.266808644666;4733.171694953404;4750.524947505256;4751.809927602926;5286.735663216824;5472.124460086367;5497.752601132918;5540.584456165804;5549.139883318092;4094.6314831665295;4117.238310912491;4119.6817018554075;4127.442795222667;4132;5788.739506722208;5836.6789826559525;5838.627922438223;5849.61977471463;5850.297964244275;4187.350960601112;4240.985531872336;4251.957480425218;4259.574042595716;4293.484781826201;4273.786310684515;4300.978495107504;4301.65586753059;4311.1808756336195;4318.222719910446;4786.401699787527;4827.586206896552;4859.684120532154;4862.902741945141;4873.171560834667;5465.207544905967;5528.226048353234;5600.775968961278;5626.324841019104;5629.211910332475;1378.3935068569997;1408.8661577150153;1410.7319609274205;1417.4117741137518;1440.157507857894;932.3473568502076;950.3632566180676;951.8286708392556;957.4159762544842;970.3062310447923;4652.651051171108;4659.95340046602;4707.670463067621;4729.314249433813;4763.95236047642;5002.99940011999;5004.3994720633755;5028.371453568304;5035.345405097314;5048;4500.694798508396;4693.882652933704;4712.505186955389;4713.562363479104;4747.881302967454;2675.411409489909;2677.759001689857;2678.9598156027673;2685.4092099738027;2686.8790904409348;5120;5122.359705036871;5167.276581278624;5170.6039369370055;5174.77484681583;4723.929141062887;5060.949390506122;5072.340595722606;5079.847604571869;5150.768215430315;5110.795568177231;5121.974390128025;5123.487651234846;5133.717645529532;5159.509846564571;4996.775789931345;5002.549770520671;5030.0191462664425;5048.671836330774;5063.417706963746;4774.7135171889795;4788.29417641243;4943.38000859545;4979.800807967786;5039.823606173766;5168.793248270027;5202.323697919246;5210.765515551885;5227.111391063604;5260.605454590845;3793.582705902293;3902.6097390260747;3927.6465118139504;3951.6641085508054;3967.940480892731;4948.228991764623;5063.924041139459;5065.924011139909;5220.921686174786;5670.8015219467125;5458.4541545845095;5499.862503437446;5511.4213007633625;5514.393416724077;5563.833085007456;5452.400235973961;5545.861353466195;5552.333719953632;5565.888682226414;5575.609707320448;5237.6333656643665;5481.643693159932;5497.917531236954;5500.697461639648;5504.779808807602;5450.672959622435;5530.423829208657;5631.493165615114;5633.155026746021;5643.209950606836;5195.454477279831;5491.670499770026;5531.668099914018;5537.784886045614;5555.2500412445415 08:41:04 INFO - PROCESS | 3003 | |5;yui.html;781.6091954022988;797.4696826610304;821.1993306526134;831.5094094484253;841.5539763925118;16040.759388609165;16846.83153168468;16894;16917.83082169178;16921.407750728726;14506;15397.461088861892;15518.068915865057;15539;15543.53369398917;680.6902859199078;793.1196372027046;817.5340056167987;819.1767273889733;827.1025936858516;805.6898094233703;819.7335865843595;820.2863508747398;821.0270829067545;822.4160845799489;859.4284800607588;862.7109918177418;862.943908645936;863.5595846118493;863.7408777366796;699.1610067918492;820.6348175062041;848.762346542969;858.0518527027638;859.2653281444369;618.2117799805239;618.8638499530097;620.0513214781391;620.1968450856107;620.8416853702322;14820.110793352325;14833.851661483355;14878.330475128538;14890.106593604418;14894.70210595793;13216.735665286737;13697.178169309873;13999.65000874976;14286.142831430148;14300;12084.939575302154;12419.503219871194;12753.808692869614;12990.28553429561;13031.65430222569;4409.823607055746;4414.492333381638;4462.576055274743;4485.282354823213;4551.522090180517;71813.64093179553;72030;72396.6380168101;72901.27098728997;75697.62151189263;11460.426978651034;11589.188756786943;11695.181337306478;11710.297382157098;11976.52093916251;21705.67441488379;21750.45623859416;21774.564508709736;21785.564288714137;21800;8425.45234559764;8557.914420855712;8798.120187981278;8817.16236957488;8848.380613357003;4623.098495793288;4936.90126197474;4949.727764972961;4963.503649635008;5017.498250175026;3490.7381946354126;3618.819059047037;3621;3658.451232315174;3667.2665466906724;632.2823595219459;650.0996120373317;651.2185377546868;654.5974225851068;655.8229278094888;672.4653900149295;690.6995456976232;723.0094770164802;769.0425420351586;791.1495142721598;9715.469833426368;9970.451625160686;10282.588696452207;10441.425721585385;10661;639.9104125422444;662.8276648071536;731.7731503234014;747.4506237915128;783.2872086401461;4909.140900342426;4967.528084831935;5055.6713813602;5088.1350170471105;5149.485051494896;111149;115590.4220478909;119120;120807;121584.39207803905;99962;103329;103618.48190759149;103712.96287037183;104460.47769761103;87113.56443217743;87193.56403217943;87341.56329218441;88761;89253;9186.219171370241;9357.251419886528;9358.204552613104;9385.296102792181;9571.234301256021;51168.488315116374;51521;52128.73935630298;52319;53595.732021339645;77021;79897;80198.19801801907;80350.19649803427;83738.74391884005;11168;13079.215247085205;13193.670158246121;13900.51348202808;14120.576382708536;15298.235088245321;15344.46294379736;15548.455804046804;15928.681426371639;15951.601209969844;14630.414783408545;14640.560783176521;14779.778303325244;15164;15650.45223417175;14464.855351446351;14697;14940.701185976437;15351.923240383727;15379.231038448257;14206.834800776085;14602.926985365006;14868.776968345268;15314.923425382802;15337.309821058303;11362.772744545227;11875.940620296844;11950.760984780429;12528.248305101723;12557.623271301876;11045.39250341239;11224.551017959517;11449.828252576384;11657.650270491898;11899.524019039141 08:41:04 INFO - PROCESS | 3003 | __end_tp_report 08:41:04 INFO - PROCESS | 3003 | __start_cc_report 08:41:04 INFO - PROCESS | 3003 | _x_x_mozilla_cycle_collect,812 08:41:04 INFO - PROCESS | 3003 | __end_cc_report 08:41:04 INFO - PROCESS | 3003 | __startTimestamp1465918864303__endTimestamp 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | ------- Summary: start ------- 08:41:04 INFO - PROCESS | 3003 | Number of tests: 6 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | [#0] dojo.html Cycles:165 Average:5379.12 Median:5552.14 stddev:613.06 (11.0%) stddev-sans-first:588.48 08:41:04 INFO - PROCESS | 3003 | Values: 3107.8 3114.9 3134.3 3145.6 3172.3 5318.8 5367.1 5407.7 5489.0 5510.3 5352.1 5401.3 5414.5 5425.4 5481.0 4800.0 4876.3 4891.7 4891.7 5034.2 4950.4 4953.0 5000.3 5007.2 5020.5 4794.8 4830.4 4838.2 4852.0 4888.7 4846.6 4864.3 4935.0 4938.7 4970.8 4887.4 4895.8 4901.3 4905.0 4967.5 5304.0 5334.3 5355.8 5364.1 5483.2 5811.7 5835.6 5849.7 5860.7 5865.0 5692.7 5915.2 5942.5 5946.3 5946.6 5404.9 5444.8 5447.9 5471.5 5525.0 5928.3 5948.8 5998.8 5999.4 6009.3 5550.0 5706.7 5749.7 5775.1 5783.2 5768.7 5861.4 5915.7 5918.1 5919.2 5784.3 5826.7 5830.4 5837.3 5857.8 5618.2 5761.0 5825.0 5860.7 5861.5 4478.9 4479.2 4485.5 4514.0 4527.0 3966.3 3991.0 3996.7 4020.3 4031.6 5339.8 5341.1 5387.6 5393.8 5485.2 5744.0 5749.3 5751.7 5759.6 5815.1 5402.7 5413.6 5421.3 5423.5 5428.0 4830.0 4836.6 4885.2 4887.9 4888.7 5960.7 5969.5 5975.3 5976.1 5978.2 5717.8 5812.5 5816.0 5830.6 5830.8 5890.8 5898.2 5947.1 5954.9 5998.7 5664.3 5665.3 5725.2 5727.5 5728.7 5681.2 5769.7 5829.2 5830.2 5831.9 5590.5 5627.8 5628.7 5678.4 5680.9 5552.1 5624.6 5672.2 5673.3 5674.5 5466.4 5508.6 5539.6 5550.7 5562.9 5666.1 5763.9 5779.6 5787.9 5801.9 5529.9 5552.1 5552.9 5557.7 5565.1 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | [#1] ext.html Cycles:180 Average:56313.03 Median:41069.49 stddev:109539.33 (266.7%) stddev-sans-first:109766.74 08:41:04 INFO - PROCESS | 3003 | Values: 598.2 610.7 618.0 633.6 643.1 32551.3 35378.6 35403.3 35446.0 43604.0 42954.6 46290.0 46568.1 46624.8 46660.5 9031.1 9205.1 9207.7 9209.0 9211.7 8982.6 9074.5 9077.4 9080.5 9086.2 9155.2 9186.1 9193.2 9193.3 9196.7 9084.5 9114.0 9123.5 9140.6 9140.8 9069.4 9134.0 9134.3 9136.7 9145.7 41919.4 42038.0 42190.6 42299.0 42413.6 3625.3 3814.8 3886.8 4000.2 4144.8 33246.7 33316.3 33380.3 33719.7 34809.8 4405.0 4406.8 4462.5 4573.3 4617.8 342239.0 343222.0 343365.0 344529.0 345236.0 35786.0 58148.4 58782.0 58884.7 58968.7 36488.0 38201.4 38422.4 38460.6 38536.6 13405.9 13470.9 13503.5 13510.9 13543.3 12946.9 13030.2 13035.2 13036.5 13050.7 8984.9 8986.8 9024.7 9081.4 9095.0 3429.3 3487.1 3523.6 3547.0 3548.4 1032.7 1033.7 1056.7 1058.9 1114.1 32771.8 32805.5 33103.3 33227.5 33378.3 1098.6 1118.8 1150.9 1187.2 1196.4 16443.9 17118.7 17363.8 17385.0 17478.7 501522.0 505370.0 506763.0 506851.0 508869.0 59737.7 60137.7 60229.0 60230.7 60266.7 1253.3 1313.2 1337.2 1369.3 1378.5 29329.3 30055.4 30388.7 30447.0 30749.8 50346.7 50433.5 50746.5 50863.7 50900.2 369325.0 373087.0 373112.0 373968.0 374360.0 26056.7 28677.9 29364.4 29509.3 31141.0 48074.0 48744.3 48912.3 49962.5 49984.8 41211.4 41475.0 41847.8 42550.1 42839.8 41165.4 41238.8 41493.8 41626.6 41772.6 38379.6 40973.6 42001.6 42126.2 42165.6 34879.8 37014.6 37090.0 37330.6 37763.2 26429.7 26584.6 26691.5 26741.9 26977.0 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | [#2] jquery.html Cycles:180 Average:27209.70 Median:2286.87 stddev:72511.70 (3170.8%) stddev-sans-first:72687.20 08:41:04 INFO - PROCESS | 3003 | Values: 410.0 410.9 412.4 419.5 427.8 18145.9 18923.8 19787.7 19791.7 19954.5 12634.9 12925.8 12926.4 12982.7 13258.0 1745.7 1761.5 1957.0 5335.8 5517.4 5245.5 5335.8 5688.7 5983.2 6021.3 6019.2 6060.6 6073.1 6084.5 6115.1 6030.6 6462.4 6541.4 6556.2 6563.5 5732.3 5758.7 5793.5 5793.8 5808.2 13730.5 14016.5 14081.9 14109.9 14290.6 2284.0 2289.8 2310.4 2367.1 2536.8 10283.2 10298.7 10401.5 10892.5 11015.3 1947.3 1991.3 2042.1 2052.9 2109.7 59851.7 61415.7 72300.6 84852.7 85754.0 10458.6 11371.7 11391.8 11392.8 11524.2 14884.3 14931.9 15005.8 15016.3 15057.1 6674.7 6959.6 8038.8 8246.6 8370.0 7982.4 7994.7 8044.6 8168.0 8273.0 5023.8 5074.0 5101.2 5140.7 5234.6 1985.0 2044.6 2051.9 2086.9 2095.4 908.2 941.7 972.3 1010.0 1056.8 19514.1 19807.5 19861.0 19869.8 20144.7 932.2 957.1 1015.4 1036.1 1114.3 9231.8 10747.6 10962.3 10984.1 11237.9 426447.0 429649.0 441561.0 442104.0 442829.0 99187.0 99476.0 99741.5 100566.0 101846.0 991.8 1050.7 1061.2 1068.6 1076.4 17739.6 17850.6 18217.5 18318.8 18621.3 40834.6 44212.0 45514.5 45852.8 46655.5 51204.7 60431.1 61770.4 62744.4 63357.1 7633.5 8001.9 8159.0 9071.0 9163.9 9708.6 13788.4 13890.5 14434.2 14620.5 11473.6 11963.2 12256.7 12379.1 12522.7 11549.8 12235.8 12292.6 12543.9 12858.6 12170.5 12213.0 12512.4 12529.7 12640.7 7803.1 8030.2 8368.5 8596.5 8733.1 5090.5 5152.2 11141.9 11610.5 12071.6 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | [#3] mootools.html Cycles:180 Average:6245.17 Median:4995.65 stddev:3631.67 (72.7%) stddev-sans-first:3623.31 08:41:04 INFO - PROCESS | 3003 | Values: 1361.1 1456.7 1497.7 1639.1 1805.5 8946.7 9359.3 9389.7 9688.9 9695.0 9423.0 9642.2 9644.1 9647.2 9649.0 2184.6 2384.4 2456.4 2541.2 2570.6 2520.3 2532.0 2532.4 2532.8 2538.2 2468.3 2503.7 2504.5 2505.7 2505.8 2121.6 2381.7 2453.2 2459.8 2463.7 2396.6 2406.9 2421.2 2422.3 2430.6 9519.1 9526.1 9573.6 9592.1 9606.5 5569.5 5702.5 5716.9 5718.9 5719.1 3622.8 3680.2 3729.0 3766.8 3802.7 1151.6 1176.9 1201.3 1201.9 1202.6 13448.6 13450.8 13465.5 13469.8 13476.8 4981.9 5040.0 5076.8 5115.2 5121.7 11050.7 11089.6 11098.9 11153.4 11166.3 5692.5 5729.7 5735.9 5739.3 5753.9 4603.1 5009.4 5152.5 5153.8 5249.1 4157.5 4182.7 4248.6 4271.7 4307.3 1351.1 1353.8 1368.0 1376.0 1376.3 823.0 848.8 923.5 941.4 966.2 6231.5 6276.6 6355.4 6414.9 6426.6 997.5 1001.7 1010.8 1030.9 1031.2 4193.3 4276.7 4297.8 4308.5 4308.9 11744.8 12161.0 12200.9 12224.1 12289.7 11224.2 11673.2 11680.5 12106.3 12136.8 8706.2 8775.5 8799.1 8802.8 8805.0 6117.2 6335.5 6392.8 6429.0 6552.2 10815.6 11033.3 11133.6 11284.0 11294.4 11302.2 11408.8 11425.8 11440.5 11447.1 8191.7 8208.8 8323.0 8445.0 8468.1 9408.5 9423.2 9455.4 9506.2 9509.3 7726.5 7955.2 8099.8 8121.8 8391.2 7975.4 8092.0 8137.0 8157.6 8228.6 7263.0 7278.0 7344.0 7352.6 7367.2 5312.2 5312.4 5312.5 5320.5 5403.6 5074.3 5124.2 5125.0 5193.0 5234.6 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | [#4] prototype.html Cycles:180 Average:4497.04 Median:5029.20 stddev:1280.03 (25.5%) stddev-sans-first:1244.72 08:41:04 INFO - PROCESS | 3003 | Values: 324.7 343.7 344.1 345.5 351.3 5222.9 5237.8 5363.4 5397.9 5410.7 5259.9 5283.7 5296.4 5313.8 5327.2 4123.7 4153.8 4179.4 4183.8 4191.2 3915.7 3996.7 4155.0 4180.7 4186.4 4044.6 4088.5 4117.2 4128.2 4131.2 4096.4 4104.9 4105.3 4110.0 4112.2 3430.6 3663.4 3679.5 3687.7 3688.1 5164.3 5192.0 5195.6 5198.5 5222.6 4722.5 4730.3 4733.2 4750.5 4751.8 5286.7 5472.1 5497.8 5540.6 5549.1 4094.6 4117.2 4119.7 4127.4 4132.0 5788.7 5836.7 5838.6 5849.6 5850.3 4187.4 4241.0 4252.0 4259.6 4293.5 4273.8 4301.0 4301.7 4311.2 4318.2 4786.4 4827.6 4859.7 4862.9 4873.2 5465.2 5528.2 5600.8 5626.3 5629.2 1378.4 1408.9 1410.7 1417.4 1440.2 932.3 950.4 951.8 957.4 970.3 4652.7 4660.0 4707.7 4729.3 4764.0 5003.0 5004.4 5028.4 5035.3 5048.0 4500.7 4693.9 4712.5 4713.6 4747.9 2675.4 2677.8 2679.0 2685.4 2686.9 5120.0 5122.4 5167.3 5170.6 5174.8 4723.9 5060.9 5072.3 5079.8 5150.8 5110.8 5122.0 5123.5 5133.7 5159.5 4996.8 5002.5 5030.0 5048.7 5063.4 4774.7 4788.3 4943.4 4979.8 5039.8 5168.8 5202.3 5210.8 5227.1 5260.6 3793.6 3902.6 3927.6 3951.7 3967.9 4948.2 5063.9 5065.9 5220.9 5670.8 5458.5 5499.9 5511.4 5514.4 5563.8 5452.4 5545.9 5552.3 5565.9 5575.6 5237.6 5481.6 5497.9 5500.7 5504.8 5450.7 5530.4 5631.5 5633.2 5643.2 5195.5 5491.7 5531.7 5537.8 5555.3 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - PROCESS | 3003 | [#5] yui.html Cycles:180 Average:21415.71 Median:4518.40 stddev:30635.75 (678.0%) stddev-sans-first:30682.51 08:41:04 INFO - PROCESS | 3003 | Values: 781.6 797.5 821.2 831.5 841.6 16040.8 16846.8 16894.0 16917.8 16921.4 14506.0 15397.5 15518.1 15539.0 15543.5 680.7 793.1 817.5 819.2 827.1 805.7 819.7 820.3 821.0 822.4 859.4 862.7 862.9 863.6 863.7 699.2 820.6 848.8 858.1 859.3 618.2 618.9 620.1 620.2 620.8 14820.1 14833.9 14878.3 14890.1 14894.7 13216.7 13697.2 13999.7 14286.1 14300.0 12084.9 12419.5 12753.8 12990.3 13031.7 4409.8 4414.5 4462.6 4485.3 4551.5 71813.6 72030.0 72396.6 72901.3 75697.6 11460.4 11589.2 11695.2 11710.3 11976.5 21705.7 21750.5 21774.6 21785.6 21800.0 8425.5 8557.9 8798.1 8817.2 8848.4 4623.1 4936.9 4949.7 4963.5 5017.5 3490.7 3618.8 3621.0 3658.5 3667.3 632.3 650.1 651.2 654.6 655.8 672.5 690.7 723.0 769.0 791.1 9715.5 9970.5 10282.6 10441.4 10661.0 639.9 662.8 731.8 747.5 783.3 4909.1 4967.5 5055.7 5088.1 5149.5 111149.0 115590.4 119120.0 120807.0 121584.4 99962.0 103329.0 103618.5 103713.0 104460.5 87113.6 87193.6 87341.6 88761.0 89253.0 9186.2 9357.3 9358.2 9385.3 9571.2 51168.5 51521.0 52128.7 52319.0 53595.7 77021.0 79897.0 80198.2 80350.2 83738.7 11168.0 13079.2 13193.7 13900.5 14120.6 15298.2 15344.5 15548.5 15928.7 15951.6 14630.4 14640.6 14779.8 15164.0 15650.5 14464.9 14697.0 14940.7 15351.9 15379.2 14206.8 14602.9 14868.8 15314.9 15337.3 11362.8 11875.9 11950.8 12528.2 12557.6 11045.4 11224.6 11449.8 11657.7 11899.5 08:41:04 INFO - PROCESS | 3003 | -------- Summary: end -------- 08:41:04 INFO - PROCESS | 3003 | 08:41:04 INFO - TEST-INFO | 3003: exit 0 08:41:04 INFO - TEST-OK | dromaeo_css | took 1113964ms 08:41:04 INFO - TEST-START | kraken 08:41:04 INFO - operating with platform_type : linux_ 08:41:04 INFO - Initialising browser for kraken test... 08:41:04 INFO - TEST-INFO | started process 3183 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAIjLXH/profile http://localhost:60800/getInfo.html) 08:41:09 INFO - PROCESS | 3183 | __metrics Screen width/height:1600/1200 08:41:09 INFO - PROCESS | 3183 | colorDepth:24 08:41:09 INFO - PROCESS | 3183 | Browser inner width/height: 1024/697 08:41:09 INFO - PROCESS | 3183 | __metrics 08:41:10 INFO - PROCESS | 3183 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:41:10 INFO - PROCESS | 3183 | console.error: PushService: 08:41:10 INFO - PROCESS | 3183 | stateChangeProcessEnqueue: Error transitioning state 08:41:10 INFO - PROCESS | 3183 | UnknownError 08:41:10 INFO - TEST-INFO | 3183: exit 0 08:41:10 INFO - Browser initialized. 08:41:10 INFO - Running cycle 1/1 for kraken test... 08:41:10 INFO - TEST-INFO | started process 3265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAIjLXH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:22 INFO - PROCESS | 3265 | RSS: Main: 173989888 08:41:22 INFO - PROCESS | 3265 | 08:41:25 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60800/tests/kraken/driver.html?testName=audio-beat-detection) 08:41:26 INFO - PROCESS | 3265 | RSS: Main: 232357888 08:41:26 INFO - PROCESS | 3265 | 08:41:28 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60800/tests/kraken/driver.html?testName=audio-dft) 08:41:29 INFO - PROCESS | 3265 | RSS: Main: 221720576 08:41:29 INFO - PROCESS | 3265 | 08:41:32 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60800/tests/kraken/driver.html?testName=audio-fft) 08:41:33 INFO - PROCESS | 3265 | RSS: Main: 286560256 08:41:33 INFO - PROCESS | 3265 | 08:41:35 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60800/tests/kraken/driver.html?testName=audio-oscillator) 08:41:35 INFO - PROCESS | 3265 | RSS: Main: 198950912 08:41:35 INFO - PROCESS | 3265 | 08:41:38 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60800/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:41:38 INFO - PROCESS | 3265 | RSS: Main: 202461184 08:41:38 INFO - PROCESS | 3265 | 08:41:41 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60800/tests/kraken/driver.html?testName=imaging-darkroom) 08:41:42 INFO - PROCESS | 3265 | RSS: Main: 237637632 08:41:42 INFO - PROCESS | 3265 | 08:41:45 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60800/tests/kraken/driver.html?testName=imaging-desaturate) 08:41:46 INFO - PROCESS | 3265 | RSS: Main: 238559232 08:41:46 INFO - PROCESS | 3265 | 08:41:49 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60800/tests/kraken/driver.html?testName=json-parse-financial) 08:41:49 INFO - PROCESS | 3265 | RSS: Main: 238645248 08:41:49 INFO - PROCESS | 3265 | 08:41:51 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60800/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:41:51 INFO - PROCESS | 3265 | RSS: Main: 227291136 08:41:51 INFO - PROCESS | 3265 | 08:41:53 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:41:54 INFO - PROCESS | 3265 | RSS: Main: 230928384 08:41:54 INFO - PROCESS | 3265 | 08:41:56 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:41:57 INFO - PROCESS | 3265 | RSS: Main: 237981696 08:41:57 INFO - PROCESS | 3265 | 08:41:59 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:42:00 INFO - PROCESS | 3265 | RSS: Main: 240799744 08:42:00 INFO - PROCESS | 3265 | 08:42:02 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:42:03 INFO - PROCESS | 3265 | RSS: Main: 240377856 08:42:03 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:60800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60800/tests/kraken/driver.html?testName=ai-astar) 08:42:05 INFO - PROCESS | 3265 | RSS: Main: 284205056 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | __start_tp_report 08:42:05 INFO - PROCESS | 3265 | _x_x_mozilla_page_load 08:42:05 INFO - PROCESS | 3265 | _x_x_mozilla_page_load_details 08:42:05 INFO - PROCESS | 3265 | |i|pagename|runs| 08:42:05 INFO - PROCESS | 3265 | |0;ai-astar;110;108;109;109;109;110;109;110;108;111 08:42:05 INFO - PROCESS | 3265 | |1;audio-beat-detection;156;130;126;177;133;126;150;124;167;133 08:42:05 INFO - PROCESS | 3265 | |2;audio-dft;162;162;161;160;160;162;161;160;159;160 08:42:05 INFO - PROCESS | 3265 | |3;audio-fft;75;75;75;135;76;76;74;75;98;76 08:42:05 INFO - PROCESS | 3265 | |4;audio-oscillator;84;85;128;84;84;85;122;85;86;130 08:42:05 INFO - PROCESS | 3265 | |5;imaging-gaussian-blur;116;117;117;117;116;116;117;117;116;117 08:42:05 INFO - PROCESS | 3265 | |6;imaging-darkroom;145;146;145;145;145;146;145;145;145;145 08:42:05 INFO - PROCESS | 3265 | |7;imaging-desaturate;96;95;95;95;94;95;95;95;95;95 08:42:05 INFO - PROCESS | 3265 | |8;json-parse-financial;53;53;53;53;53;53;54;55;52;54 08:42:05 INFO - PROCESS | 3265 | |9;json-stringify-tinderbox;60;60;60;60;61;61;61;60;60;60 08:42:05 INFO - PROCESS | 3265 | |10;stanford-crypto-aes;79;79;78;79;78;80;80;80;119;84 08:42:05 INFO - PROCESS | 3265 | |11;stanford-crypto-ccm;125;121;124;132;122;119;125;129;131;120 08:42:05 INFO - PROCESS | 3265 | |12;stanford-crypto-pbkdf2;160;159;157;164;165;159;160;162;162;165 08:42:05 INFO - PROCESS | 3265 | |13;stanford-crypto-sha256-iterative;63;64;67;64;64;63;64;63;64;62 08:42:05 INFO - PROCESS | 3265 | __end_tp_report 08:42:05 INFO - PROCESS | 3265 | __start_cc_report 08:42:05 INFO - PROCESS | 3265 | _x_x_mozilla_cycle_collect,1664 08:42:05 INFO - PROCESS | 3265 | __end_cc_report 08:42:05 INFO - PROCESS | 3265 | __startTimestamp1465918925408__endTimestamp 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | ------- Summary: start ------- 08:42:05 INFO - PROCESS | 3265 | Number of tests: 14 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#0] ai-astar Cycles:10 Average:109.30 Median:109.50 stddev:0.95 (0.9%) stddev-sans-first:0.97 08:42:05 INFO - PROCESS | 3265 | Values: 110.0 108.0 109.0 109.0 109.0 110.0 109.0 110.0 108.0 111.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:141.50 stddev:19.01 (13.4%) stddev-sans-first:19.49 08:42:05 INFO - PROCESS | 3265 | Values: 156.0 130.0 126.0 177.0 133.0 126.0 150.0 124.0 167.0 133.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 08:42:05 INFO - PROCESS | 3265 | Values: 162.0 162.0 161.0 160.0 160.0 162.0 161.0 160.0 159.0 160.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#3] audio-fft Cycles:10 Average:83.50 Median:75.50 stddev:19.47 (25.8%) stddev-sans-first:20.40 08:42:05 INFO - PROCESS | 3265 | Values: 75.0 75.0 75.0 135.0 76.0 76.0 74.0 75.0 98.0 76.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:84.50 stddev:20.37 (24.1%) stddev-sans-first:21.03 08:42:05 INFO - PROCESS | 3265 | Values: 84.0 85.0 128.0 84.0 84.0 85.0 122.0 85.0 86.0 130.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 08:42:05 INFO - PROCESS | 3265 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:42:05 INFO - PROCESS | 3265 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 08:42:05 INFO - PROCESS | 3265 | Values: 96.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 08:42:05 INFO - PROCESS | 3265 | Values: 53.0 53.0 53.0 53.0 53.0 53.0 54.0 55.0 52.0 54.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 08:42:05 INFO - PROCESS | 3265 | Values: 60.0 60.0 60.0 60.0 61.0 61.0 61.0 60.0 60.0 60.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:79.50 stddev:12.55 (15.8%) stddev-sans-first:13.20 08:42:05 INFO - PROCESS | 3265 | Values: 79.0 79.0 78.0 79.0 78.0 80.0 80.0 80.0 119.0 84.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:125.00 stddev:4.57 (3.7%) stddev-sans-first:4.84 08:42:05 INFO - PROCESS | 3265 | Values: 125.0 121.0 124.0 132.0 122.0 119.0 125.0 129.0 131.0 120.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:162.00 stddev:2.75 (1.7%) stddev-sans-first:2.88 08:42:05 INFO - PROCESS | 3265 | Values: 160.0 159.0 157.0 164.0 165.0 159.0 160.0 162.0 162.0 165.0 08:42:05 INFO - PROCESS | 3265 | 08:42:05 INFO - PROCESS | 3265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:1.32 (2.1%) stddev-sans-first:1.36 08:42:05 INFO - PROCESS | 3265 | Values: 63.0 64.0 67.0 64.0 64.0 63.0 64.0 63.0 64.0 62.0 08:42:05 INFO - PROCESS | 3265 | -------- Summary: end -------- 08:42:05 INFO - PROCESS | 3265 | 08:42:06 INFO - TEST-INFO | 3265: exit 0 08:42:06 INFO - TEST-OK | kraken | took 61448ms 08:42:06 INFO - SUITE-END | took 1175s 08:42:06 INFO - Completed test suite (00:19:35) 08:42:06 INFO - javascript benchmark 08:42:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.7979931304476, 3114.937701245975, 3134.263448089699, 3145.5910731604886, 3172.349668317995, 5318.76065577049, 5367.141257398817, 5407.702576358303, 5489.011977843987, 5510.283663123793, 5352.143657014869, 5401.297831281938, 5414.458554144584, 5425.430329815362, 5481.0, 4800.039992001596, 4876.2929375240565, 4891.682040667363, 4891.730954797485, 5034.1945288753805, 4950.4307004694565, 4953.034158339107, 5000.274960130779, 5007.198848184291, 5020.472850350716, 4794.83218087367, 4830.444498882621, 4838.201696720037, 4851.975740121312, 4888.6577939543895, 4846.6365022623095, 4864.318995340655, 4935.0, 4938.678985865908, 4970.801167953277, 4887.364642596475, 4895.804167833282, 4901.2648102784515, 4905.043516514245, 4967.453580106186, 5304.0, 5334.306540149794, 5355.812546560852, 5364.141737322048, 5483.150111732688, 5811.709414529257, 5835.649861008311, 5849.678267695275, 5860.736266868002, 5865.0, 5692.743826527816, 5915.231019967419, 5942.524598032147, 5946.256717910262, 5946.583739138262, 5404.864878378033, 5444.8094316698725, 5447.863803404907, 5471.4528547145765, 5525.0, 5928.347881733006, 5948.791792287249, 5998.790042348497, 5999.400059993965, 6009.338972712998, 5550.0, 5706.743196556165, 5749.741261643236, 5775.075987841925, 5783.219265399117, 5768.682722450306, 5861.443162899518, 5915.733791979371, 5918.053111502139, 5919.230500034968, 5784.2769653793275, 5826.70866456684, 5830.38780928005, 5837.29952405714, 5857.765689372377, 5618.213450116987, 5761.049426844524, 5824.980628390015, 5860.736266868002, 5861.472467477947, 4478.865634030984, 4479.17135329965, 4485.506594274627, 4513.977430112894, 4527.004059106991, 3966.286068507696, 3990.9822995136055, 3996.7002474814503, 4020.296448121567, 4031.6169963853386, 5339.813106541253, 5341.145416733304, 5387.595930305243, 5393.7572809223675, 5485.232067510552, 5743.994800909824, 5749.310082790008, 5751.741171647286, 5759.596828221985, 5815.098659707752, 5402.702851343214, 5413.621046526705, 5421.322334708161, 5423.457654234624, 5427.972860135674, 4829.95170048295, 4836.588889944394, 4885.169521181408, 4887.853364399074, 4888.706677599355, 5960.7019649017375, 5969.522438204933, 5975.282966044103, 5976.073708575178, 5978.1929439525975, 5717.771289148387, 5812.5349972001295, 5816.0, 5830.62100963436, 5830.7959221427045, 5890.764369425175, 5898.233229680114, 5947.137665038459, 5954.940450595612, 5998.7300571473515, 5664.291963504562, 5665.3201615806365, 5725.227094342377, 5727.513161381163, 5728.713564321851, 5681.233033540584, 5769.711514424178, 5829.213056237354, 5830.2129212555765, 5831.854203644943, 5590.468905453975, 5627.774889004558, 5628.746706398304, 5678.403767604342, 5680.943190568041, 5552.083906155439, 5624.606277560531, 5672.2342483765815, 5673.262475878109, 5674.4892959633835, 5466.3986961435, 5508.614396992172, 5539.556835453234, 5550.722463876871, 5562.888742225214, 5666.121751128581, 5763.8559036024435, 5779.595428320145, 5787.884242315046, 5801.94198058014, 5529.861753456034, 5552.139418390156, 5552.916706249491, 5557.694326811902, 5565.109582466948], "value": 5337.784492464811, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.1804927249666, 610.667186383421, 617.9876402471948, 633.6419922743646, 643.0803950350997, 32551.348973020555, 35378.64621353785, 35403.2919341613, 35446.0, 43604.0, 42954.57045429545, 46290.0, 46568.06863862721, 46624.76687616565, 46660.53339466605, 9031.096890310966, 9205.125513076247, 9207.677731279407, 9208.953955230214, 9211.677591284306, 8982.595783189772, 9074.455532668027, 9077.36458447909, 9080.455172689626, 9086.227670648002, 9155.176034156924, 9186.127317904788, 9193.172614464696, 9193.264538836911, 9196.678116265932, 9084.545772711372, 9114.0, 9123.543822808833, 9140.588673509676, 9140.817183656323, 9069.410488318304, 9133.954330228373, 9134.269258459308, 9136.725898222998, 9145.679901203492, 41919.37120943188, 42038.0, 42190.578094218974, 42299.0, 42413.57586424127, 3625.329314076904, 3814.7901865397394, 3886.8250928707994, 4000.179963107556, 4144.772037537934, 33246.6675333246, 33316.33367332664, 33380.33239335224, 33719.66280337214, 34809.825950870334, 4405.0, 4406.823727050914, 4462.486814016397, 4573.336866154422, 4617.7691115444095, 342239.0, 343222.0, 343365.0, 344529.0, 345236.0, 35786.0, 58148.418515815145, 58782.0, 58884.705576471846, 58968.705156473945, 36488.0, 38201.426978595344, 38422.423663645066, 38460.61539384626, 38536.61463385386, 13405.932970335087, 13470.93264533671, 13503.459861605426, 13510.864891351157, 13543.322833858268, 12946.935265323613, 13030.153040052366, 13035.15271507349, 13036.478540858448, 13050.738985220243, 8984.910150898408, 8986.775330616787, 9024.684136055206, 9081.364304498622, 9094.954525227331, 3429.3141371725756, 3487.093355727529, 3523.612402635708, 3546.964530354715, 3548.416099739282, 1032.6901929421144, 1033.7467320506523, 1056.6671498477954, 1058.862347894784, 1114.0753174864842, 32771.83614081914, 32805.507917381256, 33103.3379332412, 33227.50158747621, 33378.3324333512, 1098.5825386353135, 1118.78743038824, 1150.8906653867868, 1187.1749134351714, 1196.4257156564897, 16443.917780411022, 17118.743218851727, 17363.82636173622, 17385.000724916797, 17478.737818932725, 501522.0, 505370.0, 506763.0, 506851.0, 508869.0, 59737.701311494035, 60137.699311503166, 60229.0, 60230.69884650549, 60266.69866650726, 1253.254313683357, 1313.2317594207304, 1337.1776357540043, 1369.2948131712176, 1378.5175188684082, 29329.266768330963, 30055.398892022473, 30388.73480687746, 30447.0, 30749.8462507686, 50346.74826625844, 50433.49566504288, 50746.492535074176, 50863.74568127136, 50900.23649645184, 369325.0, 373087.0, 373112.0, 373968.0, 374360.0, 26056.73943260543, 28677.85661071681, 29364.41271174522, 29509.26226844346, 31141.0, 48074.0, 48744.2688359682, 48912.266316006004, 49962.50037499578, 49984.75007624939, 41211.38182927318, 41475.01824033267, 41847.79076104722, 42550.14899702051, 42839.78580107079, 41165.382519261635, 41238.79380603078, 41493.79253103836, 41626.58373416227, 41772.58227417687, 38379.616203837606, 40973.59026409698, 42001.579984199765, 42126.1574768509, 42165.57834421739, 34879.82560087183, 37014.62985370112, 37090.0, 37330.6266937338, 37763.24473510569, 26429.735702642727, 26584.601230981938, 26691.466170676867, 26741.8662906692, 26977.0], "value": 18974.40281542008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.9549049604543, 410.89727568107975, 412.4450820407064, 419.5217452104601, 427.8438369994953, 18145.909270453645, 18923.81076189238, 19787.703184452246, 19791.703124453128, 19954.501137471536, 12634.873651263484, 12925.806112908314, 12926.384201974754, 12982.740345193091, 13258.0, 1745.7119575270128, 1761.462753860072, 1956.9706454403195, 5335.8132465363815, 5517.393086760453, 5245.527902488775, 5335.759890804923, 5688.658680479164, 5983.222181116449, 6021.277446706401, 6019.157317975487, 6060.575759696823, 6073.089036644516, 6084.5132389408745, 6115.0521669141135, 6030.643105301294, 6462.353764623524, 6541.378569035958, 6556.246031706367, 6563.54055216135, 5732.283464566929, 5758.683272420015, 5793.507551858097, 5793.768249270024, 5808.244928159313, 13730.519431819937, 14016.509422170175, 14081.929590352085, 14109.929450352785, 14290.64273393163, 2283.9836272858583, 2289.759575244592, 2310.353101131686, 2367.124164059292, 2536.759007894247, 10283.177345812392, 10298.691039268833, 10401.479926003745, 10892.509837057352, 11015.284006539548, 1947.2892394275987, 1991.3428568572335, 2042.1218875883364, 2052.8768273903615, 2109.683547467877, 59851.70074149645, 61415.692921535105, 72300.63849680718, 84852.72720909253, 85754.0, 10458.581656733646, 11371.658850234506, 11391.829122563167, 11392.772144557062, 11524.193306468467, 14884.255787210595, 14931.925340373446, 15005.774913376308, 15016.324265408082, 15057.096574205583, 6674.666266686646, 6959.617221052789, 8038.758837234892, 8246.628901699438, 8369.95815020921, 7982.361411087099, 7994.680212791423, 8044.597770111471, 8167.959160204161, 8273.0, 5023.79904803811, 5074.0, 5101.158308879068, 5140.691558506502, 5234.555062819703, 1984.9503762406055, 2044.5604195098126, 2051.9487012824798, 2086.926957556478, 2095.4447071526056, 908.2416182487697, 941.7080704981478, 972.2659392159017, 1009.9646512372178, 1056.799208150449, 19514.12186451613, 19807.504812379804, 19861.0, 19869.801301987085, 20144.697829532568, 932.1517419148543, 957.1433566957674, 1015.4059874973076, 1036.0778906772978, 1114.2534501883758, 9231.76920576991, 10747.623833165802, 10962.287451315638, 10984.121270298358, 11237.943810280896, 426447.0, 429649.0, 441561.0, 442104.0, 442829.0, 99187.0, 99476.0, 99741.50129249308, 100566.0, 101846.0, 991.8462638291077, 1050.669039252633, 1061.2412125330272, 1068.56188962525, 1076.445630500293, 17739.556511087325, 17850.642987139923, 18217.45347639573, 18318.81681183171, 18621.348252811087, 40834.59165408308, 44212.0, 45514.54485455103, 45852.77073614611, 46655.53344466512, 51204.74397627988, 60431.093533596155, 61770.38229617646, 62744.372556273855, 63357.14892829926, 7633.465657404151, 8001.919980800118, 8159.0, 9071.0, 9163.86254206174, 9708.6116555337, 13788.379522921292, 13890.513832015831, 14434.206118663154, 14620.488282910048, 11473.598424055119, 11963.162578619413, 12256.693582660504, 12379.071569632493, 12522.74954500923, 11549.826752598885, 12235.816462753244, 12292.631221063382, 12543.87456125427, 12858.614241572768, 12170.452329645364, 12213.022958163503, 12512.43694033789, 12529.749405011666, 12640.6839829005, 7803.0636323639255, 8030.23712747288, 8368.497890126611, 8596.527191004305, 8733.126687331216, 5090.541851233407, 5152.175651895679, 11141.919843091197, 11610.535578576762, 12071.637850864488], "value": 8940.174929528825, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.0540674231413, 1456.7159403916235, 1497.6854860479298, 1639.1476432255229, 1805.512511621863, 8946.686865959693, 9359.298052646045, 9389.718308450749, 9688.854667179998, 9695.030496950301, 9422.952885235563, 9642.180414664745, 9644.083812037863, 9647.179989700866, 9649.0, 2184.56308738253, 2384.4277373430336, 2456.4472993576483, 2541.237628711393, 2570.588705807071, 2520.332111990324, 2531.999860055277, 2532.430203204283, 2532.7973762098986, 2538.225841118458, 2468.3458883395915, 2503.662005629235, 2504.499100179962, 2505.749425057498, 2505.799536037122, 2121.586290673319, 2381.6546600742968, 2453.1659235859806, 2459.8155138364696, 2463.7043554773363, 2396.6405039244255, 2406.867622280774, 2421.2252079334444, 2422.2854257993936, 2430.5503481855912, 9519.143277105093, 9526.142647161789, 9573.617055317778, 9592.08875156859, 9606.519674016341, 5569.470900264469, 5702.543796496312, 5716.885662286731, 5718.857028574277, 5719.14212868069, 3622.836972336251, 3680.1535646801117, 3729.0490924814, 3766.8493260269556, 3802.657760801541, 1151.5739176504662, 1176.9352685602373, 1201.34526682958, 1201.9218750781172, 1202.5790973159412, 13448.596542103753, 13450.798238026437, 13465.528706495325, 13469.797953030713, 13476.797848032287, 4981.900361992813, 5039.97480012605, 5076.82231121909, 5115.232715092766, 5121.743912804345, 11050.668479945614, 11089.55641774336, 11098.889011109946, 11153.386563739072, 11166.274192177483, 5692.544596432275, 5729.742161602738, 5735.8566035849435, 5739.311282646109, 5753.88492230153, 4603.125406172817, 5009.398872135367, 5152.510511501477, 5153.845384638467, 5249.107651699221, 4157.459530261047, 4182.728122672017, 4248.553901840324, 4271.700980931367, 4307.332363483665, 1351.094766506443, 1353.810466534686, 1368.0286996232787, 1375.9724805503834, 1376.3187222325014, 822.9753107406667, 848.8005318750089, 923.5151545438564, 941.3645789092335, 966.1932286540676, 6231.532635052389, 6276.623402595859, 6355.3644635536075, 6414.8717025659225, 6426.582272152295, 997.5311603546321, 1001.6844693921391, 1010.7877345757327, 1030.922680798928, 1031.159604922001, 4193.287141185945, 4276.700630955803, 4297.763623000765, 4308.547602501755, 4308.870733877989, 11744.765104697859, 12161.027117830728, 12200.877991219973, 12224.144309898222, 12289.69275768095, 11224.158188135922, 11673.182877198515, 11680.532778688927, 12106.273623582612, 12136.817947730791, 8706.216440520384, 8775.473471591726, 8799.076097009849, 8802.823943521094, 8805.0, 6117.174181485442, 6335.524835637438, 6392.776252831129, 6428.967855160694, 6552.180977377828, 10815.621453249098, 11033.282836615594, 11133.554657813596, 11284.0, 11294.378809165575, 11302.208845380745, 11408.82886756716, 11425.828612570984, 11440.48517816717, 11447.141464390037, 8191.713290034821, 8208.83582328362, 8322.958385208036, 8444.957775211331, 8468.110848360833, 9408.482533460725, 9423.151916327564, 9455.385399948982, 9506.191973682315, 9509.334346595948, 7726.536407815549, 7955.164707705608, 8099.757007289791, 8121.79695507617, 8391.202835730595, 7975.441719079608, 8092.018666985554, 8137.0, 8157.592120394075, 8228.62971166287, 7263.0, 7277.963610181915, 7344.045274114331, 7352.632368381666, 7367.1527774304495, 5312.229726689686, 5312.442193569619, 5312.468753124811, 5320.52115309624, 5403.567714582902, 5074.289599456153, 5124.205748109049, 5125.0, 5193.0, 5234.607404444758], "value": 4944.646705280619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.7269116186693, 343.6975461593797, 344.1224876564761, 345.49557645837075, 351.2588438394987, 5222.895542089156, 5237.816676416326, 5363.4368391318885, 5397.8948210198005, 5410.729463526823, 5259.86850328742, 5283.682979021261, 5296.364436267643, 5313.814016509423, 5327.188755823019, 4123.731957422773, 4153.813078411479, 4179.3939878717565, 4183.769892655918, 4191.203671302443, 3915.6867450604027, 3996.6802655787615, 4155.0, 4180.728252663583, 4186.434831297782, 4044.6359827615656, 4088.5093788745394, 4117.197146556423, 4128.174365126972, 4131.21506913687, 4096.361455417827, 4104.876853694364, 4105.261053010457, 4110.0, 4112.218678451115, 3430.5711786026745, 3663.358912190356, 3679.521662183899, 3687.668109870098, 3688.114852435423, 5164.328637277167, 5191.974040129775, 5195.558377537914, 5198.506141916512, 5222.608304377149, 4722.456917454468, 4730.266808644666, 4733.171694953404, 4750.524947505256, 4751.809927602926, 5286.735663216824, 5472.124460086367, 5497.752601132918, 5540.584456165804, 5549.139883318092, 4094.6314831665295, 4117.238310912491, 4119.6817018554075, 4127.442795222667, 4132.0, 5788.739506722208, 5836.6789826559525, 5838.627922438223, 5849.61977471463, 5850.297964244275, 4187.350960601112, 4240.985531872336, 4251.957480425218, 4259.574042595716, 4293.484781826201, 4273.786310684515, 4300.978495107504, 4301.65586753059, 4311.1808756336195, 4318.222719910446, 4786.401699787527, 4827.586206896552, 4859.684120532154, 4862.902741945141, 4873.171560834667, 5465.207544905967, 5528.226048353234, 5600.775968961278, 5626.324841019104, 5629.211910332475, 1378.3935068569997, 1408.8661577150153, 1410.7319609274205, 1417.4117741137518, 1440.157507857894, 932.3473568502076, 950.3632566180676, 951.8286708392556, 957.4159762544842, 970.3062310447923, 4652.651051171108, 4659.95340046602, 4707.670463067621, 4729.314249433813, 4763.95236047642, 5002.99940011999, 5004.3994720633755, 5028.371453568304, 5035.345405097314, 5048.0, 4500.694798508396, 4693.882652933704, 4712.505186955389, 4713.562363479104, 4747.881302967454, 2675.411409489909, 2677.759001689857, 2678.9598156027673, 2685.4092099738027, 2686.8790904409348, 5120.0, 5122.359705036871, 5167.276581278624, 5170.6039369370055, 5174.77484681583, 4723.929141062887, 5060.949390506122, 5072.340595722606, 5079.847604571869, 5150.768215430315, 5110.795568177231, 5121.974390128025, 5123.487651234846, 5133.717645529532, 5159.509846564571, 4996.775789931345, 5002.549770520671, 5030.0191462664425, 5048.671836330774, 5063.417706963746, 4774.7135171889795, 4788.29417641243, 4943.38000859545, 4979.800807967786, 5039.823606173766, 5168.793248270027, 5202.323697919246, 5210.765515551885, 5227.111391063604, 5260.605454590845, 3793.582705902293, 3902.6097390260747, 3927.6465118139504, 3951.6641085508054, 3967.940480892731, 4948.228991764623, 5063.924041139459, 5065.924011139909, 5220.921686174786, 5670.8015219467125, 5458.4541545845095, 5499.862503437446, 5511.4213007633625, 5514.393416724077, 5563.833085007456, 5452.400235973961, 5545.861353466195, 5552.333719953632, 5565.888682226414, 5575.609707320448, 5237.6333656643665, 5481.643693159932, 5497.917531236954, 5500.697461639648, 5504.779808807602, 5450.672959622435, 5530.423829208657, 5631.493165615114, 5633.155026746021, 5643.209950606836, 5195.454477279831, 5491.670499770026, 5531.668099914018, 5537.784886045614, 5555.2500412445415], "value": 4096.896263497204, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.6091954022988, 797.4696826610304, 821.1993306526134, 831.5094094484253, 841.5539763925118, 16040.759388609165, 16846.83153168468, 16894.0, 16917.83082169178, 16921.407750728726, 14506.0, 15397.461088861892, 15518.068915865057, 15539.0, 15543.53369398917, 680.6902859199078, 793.1196372027046, 817.5340056167987, 819.1767273889733, 827.1025936858516, 805.6898094233703, 819.7335865843595, 820.2863508747398, 821.0270829067545, 822.4160845799489, 859.4284800607588, 862.7109918177418, 862.943908645936, 863.5595846118493, 863.7408777366796, 699.1610067918492, 820.6348175062041, 848.762346542969, 858.0518527027638, 859.2653281444369, 618.2117799805239, 618.8638499530097, 620.0513214781391, 620.1968450856107, 620.8416853702322, 14820.110793352325, 14833.851661483355, 14878.330475128538, 14890.106593604418, 14894.70210595793, 13216.735665286737, 13697.178169309873, 13999.65000874976, 14286.142831430148, 14300.0, 12084.939575302154, 12419.503219871194, 12753.808692869614, 12990.28553429561, 13031.65430222569, 4409.823607055746, 4414.492333381638, 4462.576055274743, 4485.282354823213, 4551.522090180517, 71813.64093179553, 72030.0, 72396.6380168101, 72901.27098728997, 75697.62151189263, 11460.426978651034, 11589.188756786943, 11695.181337306478, 11710.297382157098, 11976.52093916251, 21705.67441488379, 21750.45623859416, 21774.564508709736, 21785.564288714137, 21800.0, 8425.45234559764, 8557.914420855712, 8798.120187981278, 8817.16236957488, 8848.380613357003, 4623.098495793288, 4936.90126197474, 4949.727764972961, 4963.503649635008, 5017.498250175026, 3490.7381946354126, 3618.819059047037, 3621.0, 3658.451232315174, 3667.2665466906724, 632.2823595219459, 650.0996120373317, 651.2185377546868, 654.5974225851068, 655.8229278094888, 672.4653900149295, 690.6995456976232, 723.0094770164802, 769.0425420351586, 791.1495142721598, 9715.469833426368, 9970.451625160686, 10282.588696452207, 10441.425721585385, 10661.0, 639.9104125422444, 662.8276648071536, 731.7731503234014, 747.4506237915128, 783.2872086401461, 4909.140900342426, 4967.528084831935, 5055.6713813602, 5088.1350170471105, 5149.485051494896, 111149.0, 115590.4220478909, 119120.0, 120807.0, 121584.39207803905, 99962.0, 103329.0, 103618.48190759149, 103712.96287037183, 104460.47769761103, 87113.56443217743, 87193.56403217943, 87341.56329218441, 88761.0, 89253.0, 9186.219171370241, 9357.251419886528, 9358.204552613104, 9385.296102792181, 9571.234301256021, 51168.488315116374, 51521.0, 52128.73935630298, 52319.0, 53595.732021339645, 77021.0, 79897.0, 80198.19801801907, 80350.19649803427, 83738.74391884005, 11168.0, 13079.215247085205, 13193.670158246121, 13900.51348202808, 14120.576382708536, 15298.235088245321, 15344.46294379736, 15548.455804046804, 15928.681426371639, 15951.601209969844, 14630.414783408545, 14640.560783176521, 14779.778303325244, 15164.0, 15650.45223417175, 14464.855351446351, 14697.0, 14940.701185976437, 15351.923240383727, 15379.231038448257, 14206.834800776085, 14602.926985365006, 14868.776968345268, 15314.923425382802, 15337.309821058303, 11362.772744545227, 11875.940620296844, 11950.760984780429, 12528.248305101723, 12557.623271301876, 11045.39250341239, 11224.551017959517, 11449.828252576384, 11657.650270491898, 11899.524019039141], "value": 7806.5348296015845, "unit": "score"}], "name": "dromaeo_css", "value": 7233.152520745128, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 108.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 108.0, 111.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 130.0, 126.0, 177.0, 133.0, 126.0, 150.0, 124.0, 167.0, 133.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 160.0, 160.0, 162.0, 161.0, 160.0, 159.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 135.0, 76.0, 76.0, 74.0, 75.0, 98.0, 76.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 128.0, 84.0, 84.0, 85.0, 122.0, 85.0, 86.0, 130.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 55.0, 52.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 79.0, 78.0, 80.0, 80.0, 80.0, 119.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 124.0, 132.0, 122.0, 119.0, 125.0, 129.0, 131.0, 120.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 157.0, 164.0, 165.0, 159.0, 160.0, 162.0, 162.0, 165.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 67.0, 64.0, 64.0, 63.0, 64.0, 63.0, 64.0, 62.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1496.8999999999996, "alertThreshold": 2.0}]} 08:42:06 INFO - Return code: 0 08:42:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:42:06 INFO - # TBPL SUCCESS # 08:42:06 INFO - Running post-action listener: _resource_record_post_action 08:42:06 INFO - ##### 08:42:06 INFO - ##### Finished run-tests step (success) 08:42:06 INFO - ##### 08:42:06 INFO - Running post-run listener: _resource_record_post_run 08:42:06 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548397056; Read time: 8; Write time: 385264 08:42:06 INFO - TinderboxPrint: CPU usage
13.3% 08:42:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:42:06 INFO - TinderboxPrint: I/O write bytes / time
548,397,056 / 385,264 08:42:06 INFO - TinderboxPrint: CPU idle
8,263.2 (86.7%) 08:42:06 INFO - TinderboxPrint: CPU user
1,216.1 (12.8%) 08:42:06 INFO - TinderboxPrint: Swap in / out
0 / 0 08:42:06 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 255451136; Read time: 0; Write time: 227964 08:42:06 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292945920; Read time: 8; Write time: 157300 08:42:07 INFO - Running post-run listener: _upload_blobber_files 08:42:07 INFO - Blob upload gear active. 08:42:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:42:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:42:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:42:07 INFO - (blobuploader) - INFO - Open directory for files ... 08:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:42:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:11 INFO - (blobuploader) - INFO - Done attempting. 08:42:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:42:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:42:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:12 INFO - (blobuploader) - INFO - Done attempting. 08:42:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:42:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:42:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:12 INFO - (blobuploader) - INFO - Done attempting. 08:42:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:42:12 INFO - Return code: 0 08:42:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:42:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:42:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f19bfca9755cc4ec25d8b2d530d2bd46eab3e623b107f1ece5da14a5affde32dff0315c509e46c79f2986ba0be042dd47689d76be1514c54ca6fd29ac9437425", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f2610fa14c59526283d6e61192bfbe1e2c3dd30f6133830fd77e91648a79fecd2db83dab551739f691220cc68ad728593ef726232e5a6c4968bb5edbae24f0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef9021d49fed260f96411a0ea7d08f4ec125077b06737cd5ca436c5dd7b7496d93a1b2e0035fb61c046abfa6c90f4302bc0c8a3ea60030b072593a272eb43cb5"} 08:42:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:42:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:42:12 INFO - Contents: 08:42:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f19bfca9755cc4ec25d8b2d530d2bd46eab3e623b107f1ece5da14a5affde32dff0315c509e46c79f2986ba0be042dd47689d76be1514c54ca6fd29ac9437425", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f2610fa14c59526283d6e61192bfbe1e2c3dd30f6133830fd77e91648a79fecd2db83dab551739f691220cc68ad728593ef726232e5a6c4968bb5edbae24f0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef9021d49fed260f96411a0ea7d08f4ec125077b06737cd5ca436c5dd7b7496d93a1b2e0035fb61c046abfa6c90f4302bc0c8a3ea60030b072593a272eb43cb5"} 08:42:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:42:12 INFO - Copying logs to upload dir... 08:42:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:42:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.836975 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-14 08:42:12.668998) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 08:42:12.672917) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f19bfca9755cc4ec25d8b2d530d2bd46eab3e623b107f1ece5da14a5affde32dff0315c509e46c79f2986ba0be042dd47689d76be1514c54ca6fd29ac9437425", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f2610fa14c59526283d6e61192bfbe1e2c3dd30f6133830fd77e91648a79fecd2db83dab551739f691220cc68ad728593ef726232e5a6c4968bb5edbae24f0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef9021d49fed260f96411a0ea7d08f4ec125077b06737cd5ca436c5dd7b7496d93a1b2e0035fb61c046abfa6c90f4302bc0c8a3ea60030b072593a272eb43cb5"} build_url:https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/SqDFH2XlQLOpBaq642OTZQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f19bfca9755cc4ec25d8b2d530d2bd46eab3e623b107f1ece5da14a5affde32dff0315c509e46c79f2986ba0be042dd47689d76be1514c54ca6fd29ac9437425", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f2610fa14c59526283d6e61192bfbe1e2c3dd30f6133830fd77e91648a79fecd2db83dab551739f691220cc68ad728593ef726232e5a6c4968bb5edbae24f0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef9021d49fed260f96411a0ea7d08f4ec125077b06737cd5ca436c5dd7b7496d93a1b2e0035fb61c046abfa6c90f4302bc0c8a3ea60030b072593a272eb43cb5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 08:42:12.697824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 08:42:12.698152) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465916866.920787-1168984396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004502 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 08:42:12.783391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 08:42:12.783709) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 08:42:12.783994) ========= ========= Total master_lag: 0.17 =========