builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-041 starttime: 1466532560.74 results: success (0) buildid: 20160621083052 builduid: 52f218857c63453c9ab390f632936197 revision: ecd3562339dcd51a604e82efbcb347de8b823aee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:20.739699) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:20.740095) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:20.740386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:20.756969) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:20.757276) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-21 11:09:20-- 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% 70.9M=0s 2016-06-21 11:09:21 (70.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082669 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.057017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.057349) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020691 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.096684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.097044) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ecd3562339dcd51a604e82efbcb347de8b823aee --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 ecd3562339dcd51a604e82efbcb347de8b823aee --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=8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-21 11:09:21,319 truncating revision to first 12 chars 2016-06-21 11:09:21,319 Setting DEBUG logging. 2016-06-21 11:09:21,319 attempt 1/10 2016-06-21 11:09:21,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ecd3562339dc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 11:09:21,701 unpacking tar archive at: fx-team-ecd3562339dc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.706199 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.816511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.816828) ========= script_repo_revision: ecd3562339dcd51a604e82efbcb347de8b823aee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.817225) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.817506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 11:09:21.824662) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-21 11:09:21.824987) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:09:22 INFO - MultiFileLogger online at 20160621 11:09:22 in /builds/slave/test 11:09:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:09:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:09:22 INFO - {'append_to_log': False, 11:09:22 INFO - 'base_work_dir': '/builds/slave/test', 11:09:22 INFO - 'blob_upload_branch': 'Fx-Team', 11:09:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:09:22 INFO - 'branch': 'Fx-Team', 11:09:22 INFO - 'buildbot_json_path': 'buildprops.json', 11:09:22 INFO - 'config_files': ('talos/linux_config.py',), 11:09:22 INFO - 'default_actions': ('clobber', 11:09:22 INFO - 'read-buildbot-config', 11:09:22 INFO - 'download-and-extract', 11:09:22 INFO - 'populate-webroot', 11:09:22 INFO - 'create-virtualenv', 11:09:22 INFO - 'install', 11:09:22 INFO - 'run-tests'), 11:09:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:09:22 INFO - 'download_minidump_stackwalk': True, 11:09:22 INFO - 'download_symbols': 'ondemand', 11:09:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:09:22 INFO - 'tooltool.py': '/tools/tooltool.py', 11:09:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:09:22 INFO - '/tools/misc-python/virtualenv.py')}, 11:09:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:09:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:09:22 INFO - 'installer_path': 'installer.exe', 11:09:22 INFO - 'log_level': 'info', 11:09:22 INFO - 'log_name': 'talos', 11:09:22 INFO - 'log_to_console': True, 11:09:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:09:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:09:22 INFO - 'opt_config_files': (), 11:09:22 INFO - 'pip_index': False, 11:09:22 INFO - 'sps_profile': False, 11:09:22 INFO - 'sps_profile_interval': 0, 11:09:22 INFO - 'suite': 'dromaeojs-e10s', 11:09:22 INFO - 'system_bits': '32', 11:09:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:09:22 INFO - 'title': 'talos-linux64-ix-041', 11:09:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:09:22 INFO - 'use_talos_json': True, 11:09:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:09:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:09:22 INFO - 'work_dir': 'build'} 11:09:22 INFO - ##### 11:09:22 INFO - ##### Running clobber step. 11:09:22 INFO - ##### 11:09:22 INFO - Running pre-action listener: _resource_record_pre_action 11:09:22 INFO - Running main action method: clobber 11:09:22 INFO - rmtree: /builds/slave/test/build 11:09:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:09:22 INFO - Running post-action listener: _resource_record_post_action 11:09:22 INFO - ##### 11:09:22 INFO - ##### Finished clobber step (success) 11:09:22 INFO - ##### 11:09:22 INFO - ##### 11:09:22 INFO - ##### Running read-buildbot-config step. 11:09:22 INFO - ##### 11:09:22 INFO - Running pre-action listener: _resource_record_pre_action 11:09:22 INFO - Running main action method: read_buildbot_config 11:09:22 INFO - Using buildbot properties: 11:09:22 INFO - { 11:09:22 INFO - "project": "", 11:09:22 INFO - "product": "firefox", 11:09:22 INFO - "script_repo_revision": "production", 11:09:22 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 11:09:22 INFO - "repository": "", 11:09:22 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 11:09:22 INFO - "buildid": "20160621083052", 11:09:22 INFO - "pgo_build": "True", 11:09:22 INFO - "basedir": "/builds/slave/test", 11:09:22 INFO - "buildnumber": 565, 11:09:22 INFO - "slavename": "talos-linux64-ix-041", 11:09:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:09:22 INFO - "platform": "ubuntu64_hw", 11:09:22 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 11:09:22 INFO - "branch": "fx-team", 11:09:22 INFO - "repo_path": "integration/fx-team", 11:09:22 INFO - "revision": "ecd3562339dcd51a604e82efbcb347de8b823aee", 11:09:22 INFO - "stage_platform": "linux64-pgo", 11:09:22 INFO - "builduid": "52f218857c63453c9ab390f632936197", 11:09:22 INFO - "slavebuilddir": "test" 11:09:22 INFO - } 11:09:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:09:22 INFO - Running post-action listener: _resource_record_post_action 11:09:22 INFO - ##### 11:09:22 INFO - ##### Finished read-buildbot-config step (success) 11:09:22 INFO - ##### 11:09:22 INFO - ##### 11:09:22 INFO - ##### Running download-and-extract step. 11:09:22 INFO - ##### 11:09:22 INFO - Running pre-action listener: _resource_record_pre_action 11:09:22 INFO - Running main action method: download_and_extract 11:09:22 INFO - mkdir: /builds/slave/test/build/tests 11:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:22 INFO - https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:09:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:09:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:09:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:23 INFO - Downloaded 1361 bytes. 11:09:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:09:23 INFO - Using the following test package requirements: 11:09:23 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:09:23 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:09:23 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:09:23 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'jsshell-linux-x86_64.zip'], 11:09:23 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:09:23 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:09:23 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:09:23 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:09:23 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:09:23 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:09:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:09:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:09:23 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')]} 11:09:23 INFO - https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:25 INFO - Downloaded 24225158 bytes. 11:09:25 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'] 11:09:25 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 11:09:27 INFO - Return code: 0 11:09:27 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 11:09: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')]} 11:09:27 INFO - https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:09:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:09:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:09:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:28 INFO - Downloaded 24225158 bytes. 11:09:28 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'] 11:09:28 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 11:09:29 INFO - Return code: 0 11:09: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')]} 11:09:29 INFO - https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:09:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:09:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:09:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:31 INFO - Downloaded 14405384 bytes. 11:09:31 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'] 11:09:31 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 11:09:31 INFO - Return code: 0 11:09:31 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')]} 11:09:31 INFO - https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:09:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:09:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:09:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMV7shOCS1eptfHjzLhAxA/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 11:09:34 INFO - Downloaded 60130839 bytes. 11:09:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:09:34 INFO - mkdir: /builds/slave/test/properties 11:09:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:09:34 INFO - Writing to file /builds/slave/test/properties/build_url 11:09:34 INFO - Contents: 11:09:34 INFO - build_url:https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:09:36 INFO - Running post-action listener: _resource_record_post_action 11:09:36 INFO - Running post-action listener: set_extra_try_arguments 11:09:36 INFO - ##### 11:09:36 INFO - ##### Finished download-and-extract step (success) 11:09:36 INFO - ##### 11:09:36 INFO - ##### 11:09:36 INFO - ##### Running populate-webroot step. 11:09:36 INFO - ##### 11:09:36 INFO - Running pre-action listener: _resource_record_pre_action 11:09:36 INFO - Running main action method: populate_webroot 11:09:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:09:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:09:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:09:36 INFO - '--tppagecycles', 11:09:36 INFO - '7'], 11:09:36 INFO - 'tests': ['tsvgm']}}, 11:09:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:09:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:09:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:09:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:09:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['damp', 'tps']}, 11:09:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'tests': ['damp', 'tps']}, 11:09:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['dromaeo_dom']}, 11:09:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:09:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['basic_compositor_video']}, 11:09:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:09:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['a11yr', 11:09:36 INFO - 'ts_paint', 11:09:36 INFO - 'tpaint', 11:09:36 INFO - 'sessionrestore', 11:09:36 INFO - 'sessionrestore_no_auto_restore', 11:09:36 INFO - 'tabpaint']}, 11:09:36 INFO - 'other-e10s': {'tests': ['a11yr', 11:09:36 INFO - 'ts_paint', 11:09:36 INFO - 'tpaint', 11:09:36 INFO - 'sessionrestore', 11:09:36 INFO - 'sessionrestore_no_auto_restore', 11:09:36 INFO - 'tabpaint']}, 11:09:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:09:36 INFO - 'ts_paint', 11:09:36 INFO - 'tpaint', 11:09:36 INFO - 'sessionrestore', 11:09:36 INFO - 'sessionrestore_no_auto_restore', 11:09:36 INFO - 'tabpaint']}, 11:09:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:09:36 INFO - 'ts_paint', 11:09:36 INFO - 'tpaint', 11:09:36 INFO - 'sessionrestore', 11:09:36 INFO - 'sessionrestore_no_auto_restore', 11:09:36 INFO - 'tabpaint']}, 11:09:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['a11yr', 11:09:36 INFO - 'ts_paint', 11:09:36 INFO - 'tpaint', 11:09:36 INFO - 'sessionrestore', 11:09:36 INFO - 'sessionrestore_no_auto_restore', 11:09:36 INFO - 'tabpaint']}, 11:09:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['a11yr', 11:09:36 INFO - 'ts_paint', 11:09:36 INFO - 'tpaint', 11:09:36 INFO - 'sessionrestore', 11:09:36 INFO - 'sessionrestore_no_auto_restore', 11:09:36 INFO - 'tabpaint']}, 11:09:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['tsvgx', 11:09:36 INFO - 'tsvgr_opacity', 11:09:36 INFO - 'tart', 11:09:36 INFO - 'tscrollx', 11:09:36 INFO - 'cart']}, 11:09:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:09:36 INFO - 'tsvgr_opacity', 11:09:36 INFO - 'tart', 11:09:36 INFO - 'tscrollx', 11:09:36 INFO - 'cart']}, 11:09:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'talos_options': ['--disable-e10s'], 11:09:36 INFO - 'tests': ['tp5o']}, 11:09:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'tests': ['tp5o']}, 11:09:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'talos_options': ['--disable-e10s', 11:09:36 INFO - '--xperf_path', 11:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:36 INFO - 'tests': ['tp5n']}, 11:09:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:36 INFO - 'talos_options': ['--xperf_path', 11:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:36 INFO - 'tests': ['tp5n']}}, 11:09:36 INFO - 'talos.zip': {'path': '', 11:09:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:09:36 INFO - Running post-action listener: _resource_record_post_action 11:09:36 INFO - ##### 11:09:36 INFO - ##### Finished populate-webroot step (success) 11:09:36 INFO - ##### 11:09:36 INFO - ##### 11:09:36 INFO - ##### Running create-virtualenv step. 11:09:36 INFO - ##### 11:09:36 INFO - Running pre-action listener: _resource_record_pre_action 11:09:36 INFO - Running main action method: create_virtualenv 11:09:36 INFO - Creating virtualenv /builds/slave/test/build/venv 11:09:36 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 11:09:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:09:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:09:36 INFO - Using real prefix '/usr' 11:09:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:09:36 INFO - Installing distribute.............................................................................................................................................................................................done. 11:09:38 INFO - Installing pip.................done. 11:09:38 INFO - Return code: 0 11:09:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:38 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:38 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 11:09:38 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 11:09:38 INFO - Using env: {'DISPLAY': ':0', 11:09:38 INFO - 'HOME': '/home/cltbld', 11:09:38 INFO - 'LANG': 'en_US.UTF-8', 11:09:38 INFO - 'LANGUAGE': 'en_US:en', 11:09:38 INFO - 'LOGNAME': 'cltbld', 11:09:38 INFO - 'MAIL': '/var/mail/cltbld', 11:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:38 INFO - 'MOZ_NO_REMOTE': '1', 11:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:38 INFO - 'NO_EM_RESTART': '1', 11:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:38 INFO - 'PWD': '/builds/slave/test', 11:09:38 INFO - 'SHELL': '/bin/bash', 11:09:38 INFO - 'SHLVL': '1', 11:09:38 INFO - 'TERM': 'linux', 11:09:38 INFO - 'TMOUT': '86400', 11:09:38 INFO - 'USER': 'cltbld', 11:09:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:38 INFO - '_': '/tools/buildbot/bin/python'} 11:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:38 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 11:09:38 INFO - Cleaning up... 11:09:38 INFO - Return code: 0 11:09:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:38 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:38 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 11:09:38 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 11:09:38 INFO - Using env: {'DISPLAY': ':0', 11:09:38 INFO - 'HOME': '/home/cltbld', 11:09:38 INFO - 'LANG': 'en_US.UTF-8', 11:09:38 INFO - 'LANGUAGE': 'en_US:en', 11:09:38 INFO - 'LOGNAME': 'cltbld', 11:09:38 INFO - 'MAIL': '/var/mail/cltbld', 11:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:38 INFO - 'MOZ_NO_REMOTE': '1', 11:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:38 INFO - 'NO_EM_RESTART': '1', 11:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:38 INFO - 'PWD': '/builds/slave/test', 11:09:38 INFO - 'SHELL': '/bin/bash', 11:09:38 INFO - 'SHLVL': '1', 11:09:38 INFO - 'TERM': 'linux', 11:09:38 INFO - 'TMOUT': '86400', 11:09:38 INFO - 'USER': 'cltbld', 11:09:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:38 INFO - '_': '/tools/buildbot/bin/python'} 11:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:39 INFO - Downloading/unpacking psutil>=3.1.1 11:09:39 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 11:09:39 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 11:09:39 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 11:09:39 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 11:09:39 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 11:09:39 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 11:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:41 INFO - Installing collected packages: psutil 11:09:41 INFO - Running setup.py install for psutil 11:09:41 INFO - building 'psutil._psutil_linux' extension 11:09:41 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 11:09:41 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 11:09:41 INFO - building 'psutil._psutil_posix' extension 11:09:41 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 11:09:41 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 11:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:41 INFO - Successfully installed psutil 11:09:41 INFO - Cleaning up... 11:09:41 INFO - Return code: 0 11:09:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:09:41 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')]} 11:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:41 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')]} 11:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:41 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:41 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 11:09:41 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 11:09:41 INFO - Using env: {'DISPLAY': ':0', 11:09:41 INFO - 'HOME': '/home/cltbld', 11:09:41 INFO - 'LANG': 'en_US.UTF-8', 11:09:41 INFO - 'LANGUAGE': 'en_US:en', 11:09:41 INFO - 'LOGNAME': 'cltbld', 11:09:41 INFO - 'MAIL': '/var/mail/cltbld', 11:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:41 INFO - 'MOZ_NO_REMOTE': '1', 11:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:41 INFO - 'NO_EM_RESTART': '1', 11:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:41 INFO - 'PWD': '/builds/slave/test', 11:09:41 INFO - 'SHELL': '/bin/bash', 11:09:41 INFO - 'SHLVL': '1', 11:09:41 INFO - 'TERM': 'linux', 11:09:41 INFO - 'TMOUT': '86400', 11:09:41 INFO - 'USER': 'cltbld', 11:09:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:41 INFO - '_': '/tools/buildbot/bin/python'} 11:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:09:43 INFO - Installing collected packages: mozsystemmonitor 11:09:43 INFO - Running setup.py install for mozsystemmonitor 11:09:43 INFO - Successfully installed mozsystemmonitor 11:09:43 INFO - Cleaning up... 11:09:44 INFO - Return code: 0 11:09:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:09:44 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')]} 11:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:44 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')]} 11:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:44 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:44 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 11:09:44 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 11:09:44 INFO - Using env: {'DISPLAY': ':0', 11:09:44 INFO - 'HOME': '/home/cltbld', 11:09:44 INFO - 'LANG': 'en_US.UTF-8', 11:09:44 INFO - 'LANGUAGE': 'en_US:en', 11:09:44 INFO - 'LOGNAME': 'cltbld', 11:09:44 INFO - 'MAIL': '/var/mail/cltbld', 11:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:44 INFO - 'MOZ_NO_REMOTE': '1', 11:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:44 INFO - 'NO_EM_RESTART': '1', 11:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:44 INFO - 'PWD': '/builds/slave/test', 11:09:44 INFO - 'SHELL': '/bin/bash', 11:09:44 INFO - 'SHLVL': '1', 11:09:44 INFO - 'TERM': 'linux', 11:09:44 INFO - 'TMOUT': '86400', 11:09:44 INFO - 'USER': 'cltbld', 11:09:44 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:44 INFO - '_': '/tools/buildbot/bin/python'} 11:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:44 INFO - Downloading/unpacking jsonschema==2.5.1 11:09:44 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 11:09:44 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 11:09:44 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 11:09:44 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 11:09:44 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 11:09:44 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 11:09:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:09:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:46 INFO - Downloading functools32-3.2.3-2.tar.gz 11:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:09:46 INFO - warning: no files found matching '*.txt' 11:09:46 INFO - no previously-included directories found matching 'build' 11:09:46 INFO - no previously-included directories found matching 'dist' 11:09:46 INFO - no previously-included directories found matching '.git*' 11:09:46 INFO - Installing collected packages: jsonschema, functools32 11:09:46 INFO - Running setup.py install for functools32 11:09:46 INFO - warning: no files found matching '*.txt' 11:09:46 INFO - no previously-included directories found matching 'build' 11:09:46 INFO - no previously-included directories found matching 'dist' 11:09:46 INFO - no previously-included directories found matching '.git*' 11:09:46 INFO - Successfully installed jsonschema functools32 11:09:46 INFO - Cleaning up... 11:09:46 INFO - Return code: 0 11:09:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09: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')]} 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:46 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:46 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 11:09:46 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 11:09:46 INFO - Using env: {'DISPLAY': ':0', 11:09:46 INFO - 'HOME': '/home/cltbld', 11:09:46 INFO - 'LANG': 'en_US.UTF-8', 11:09:46 INFO - 'LANGUAGE': 'en_US:en', 11:09:46 INFO - 'LOGNAME': 'cltbld', 11:09:46 INFO - 'MAIL': '/var/mail/cltbld', 11:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:46 INFO - 'MOZ_NO_REMOTE': '1', 11:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:46 INFO - 'NO_EM_RESTART': '1', 11:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:46 INFO - 'PWD': '/builds/slave/test', 11:09:46 INFO - 'SHELL': '/bin/bash', 11:09:46 INFO - 'SHLVL': '1', 11:09:46 INFO - 'TERM': 'linux', 11:09:46 INFO - 'TMOUT': '86400', 11:09:46 INFO - 'USER': 'cltbld', 11:09:46 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:46 INFO - '_': '/tools/buildbot/bin/python'} 11:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:46 INFO - Downloading/unpacking blobuploader==1.2.4 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:48 INFO - Downloading blobuploader-1.2.4.tar.gz 11:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:09:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:09:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 INFO - Downloading docopt-0.6.1.tar.gz 11:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:09:49 INFO - Installing collected packages: blobuploader, requests, docopt 11:09:49 INFO - Running setup.py install for blobuploader 11:09:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:09:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:09:49 INFO - Running setup.py install for requests 11:09:49 INFO - Running setup.py install for docopt 11:09:49 INFO - Successfully installed blobuploader requests docopt 11:09:49 INFO - Cleaning up... 11:09:50 INFO - Return code: 0 11:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09: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')]} 11:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:50 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:50 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 11:09:50 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 11:09:50 INFO - Using env: {'DISPLAY': ':0', 11:09:50 INFO - 'HOME': '/home/cltbld', 11:09:50 INFO - 'LANG': 'en_US.UTF-8', 11:09:50 INFO - 'LANGUAGE': 'en_US:en', 11:09:50 INFO - 'LOGNAME': 'cltbld', 11:09:50 INFO - 'MAIL': '/var/mail/cltbld', 11:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:50 INFO - 'MOZ_NO_REMOTE': '1', 11:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:50 INFO - 'NO_EM_RESTART': '1', 11:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:50 INFO - 'PWD': '/builds/slave/test', 11:09:50 INFO - 'SHELL': '/bin/bash', 11:09:50 INFO - 'SHLVL': '1', 11:09:50 INFO - 'TERM': 'linux', 11:09:50 INFO - 'TMOUT': '86400', 11:09:50 INFO - 'USER': 'cltbld', 11:09:50 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:50 INFO - '_': '/tools/buildbot/bin/python'} 11:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:50 INFO - Running setup.py (path:/tmp/pip-y3UBN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:50 INFO - Running setup.py (path:/tmp/pip-9wy240-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:50 INFO - Running setup.py (path:/tmp/pip-GTn94Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:50 INFO - Running setup.py (path:/tmp/pip-9ukWrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:50 INFO - Running setup.py (path:/tmp/pip-J12E0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:50 INFO - Running setup.py (path:/tmp/pip-DvjGOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:50 INFO - Running setup.py (path:/tmp/pip-URObbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:50 INFO - Running setup.py (path:/tmp/pip-hWPUAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:50 INFO - Running setup.py (path:/tmp/pip-xfy6eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:50 INFO - Running setup.py (path:/tmp/pip-s4YD24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:50 INFO - Running setup.py (path:/tmp/pip-ahCOao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:51 INFO - Running setup.py (path:/tmp/pip-DrdP36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:51 INFO - Running setup.py (path:/tmp/pip-7n4ptl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:51 INFO - Running setup.py (path:/tmp/pip-KNsnSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:51 INFO - Running setup.py (path:/tmp/pip-MmVzhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:51 INFO - Running setup.py (path:/tmp/pip-uucpuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:51 INFO - Running setup.py (path:/tmp/pip-06PtDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:09:51 INFO - Running setup.py install for manifestparser 11:09:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:09:51 INFO - Running setup.py install for mozcrash 11:09:51 INFO - Running setup.py install for mozdebug 11:09:51 INFO - Running setup.py install for mozdevice 11:09:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:09:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:09:51 INFO - Running setup.py install for mozfile 11:09:52 INFO - Running setup.py install for mozhttpd 11:09:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for mozinfo 11:09:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for mozInstall 11:09:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for mozleak 11:09:52 INFO - Running setup.py install for mozlog 11:09:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for moznetwork 11:09:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for mozprocess 11:09:52 INFO - Running setup.py install for mozprofile 11:09:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for mozrunner 11:09:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:09:52 INFO - Running setup.py install for mozscreenshot 11:09:53 INFO - Running setup.py install for moztest 11:09:53 INFO - Running setup.py install for mozversion 11:09:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:09:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:09:53 INFO - Cleaning up... 11:09:53 INFO - Return code: 0 11:09:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09: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')]} 11:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:53 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:53 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 11:09:53 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 11:09:53 INFO - Using env: {'DISPLAY': ':0', 11:09:53 INFO - 'HOME': '/home/cltbld', 11:09:53 INFO - 'LANG': 'en_US.UTF-8', 11:09:53 INFO - 'LANGUAGE': 'en_US:en', 11:09:53 INFO - 'LOGNAME': 'cltbld', 11:09:53 INFO - 'MAIL': '/var/mail/cltbld', 11:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:53 INFO - 'MOZ_NO_REMOTE': '1', 11:09:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:53 INFO - 'NO_EM_RESTART': '1', 11:09:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:53 INFO - 'PWD': '/builds/slave/test', 11:09:53 INFO - 'SHELL': '/bin/bash', 11:09:53 INFO - 'SHLVL': '1', 11:09:53 INFO - 'TERM': 'linux', 11:09:53 INFO - 'TMOUT': '86400', 11:09:53 INFO - 'USER': 'cltbld', 11:09:53 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:53 INFO - '_': '/tools/buildbot/bin/python'} 11:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:53 INFO - Running setup.py (path:/tmp/pip-ETEZCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:53 INFO - Running setup.py (path:/tmp/pip-P6XX8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:53 INFO - Running setup.py (path:/tmp/pip-oY4ULc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:53 INFO - Running setup.py (path:/tmp/pip-_R9TyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:53 INFO - Running setup.py (path:/tmp/pip-Gxyy9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:53 INFO - Running setup.py (path:/tmp/pip-YVZCiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:53 INFO - Running setup.py (path:/tmp/pip-nR9KGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:53 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)) 11:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:54 INFO - Running setup.py (path:/tmp/pip-78sfuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:54 INFO - Running setup.py (path:/tmp/pip-C7KBDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:54 INFO - Running setup.py (path:/tmp/pip-81VWTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:54 INFO - Running setup.py (path:/tmp/pip-DkFLCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:54 INFO - Running setup.py (path:/tmp/pip-lGWzS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:54 INFO - Running setup.py (path:/tmp/pip-pjO3py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:54 INFO - Running setup.py (path:/tmp/pip-cjoiNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:54 INFO - Running setup.py (path:/tmp/pip-Ag9JtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:54 INFO - Running setup.py (path:/tmp/pip-Vy1lem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:54 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)) 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:54 INFO - Running setup.py (path:/tmp/pip-iFVEO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:54 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)) 11:09:54 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)) 11:09:54 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)) 11:09:54 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)) 11:09:54 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)) 11:09:54 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)) 11:09:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09:56 INFO - Downloading blessings-1.6.tar.gz 11:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:09:56 INFO - Installing collected packages: blessings 11:09:56 INFO - Running setup.py install for blessings 11:09:56 INFO - Successfully installed blessings 11:09:56 INFO - Cleaning up... 11:09:56 INFO - Return code: 0 11:09:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:57 INFO - Reading from file tmpfile_stdout 11:09:57 INFO - Current package versions: 11:09:57 INFO - argparse == 1.2.1 11:09:57 INFO - blessings == 1.6 11:09:57 INFO - blobuploader == 1.2.4 11:09:57 INFO - docopt == 0.6.1 11:09:57 INFO - functools32 == 3.2.3-2 11:09:57 INFO - jsonschema == 2.5.1 11:09:57 INFO - manifestparser == 1.1 11:09:57 INFO - mozInstall == 1.12 11:09:57 INFO - mozcrash == 0.17 11:09:57 INFO - mozdebug == 0.1 11:09:57 INFO - mozdevice == 0.48 11:09:57 INFO - mozfile == 1.2 11:09:57 INFO - mozhttpd == 0.7 11:09:57 INFO - mozinfo == 0.9 11:09:57 INFO - mozleak == 0.1 11:09:57 INFO - mozlog == 3.2 11:09:57 INFO - moznetwork == 0.27 11:09:57 INFO - mozprocess == 0.22 11:09:57 INFO - mozprofile == 0.28 11:09:57 INFO - mozrunner == 6.11 11:09:57 INFO - mozscreenshot == 0.1 11:09:57 INFO - mozsystemmonitor == 0.3 11:09:57 INFO - moztest == 0.7 11:09:57 INFO - mozversion == 1.4 11:09:57 INFO - psutil == 3.1.1 11:09:57 INFO - requests == 1.2.3 11:09:57 INFO - wsgiref == 0.1.2 11:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:57 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:57 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 11:09:57 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 11:09:57 INFO - Using env: {'DISPLAY': ':0', 11:09:57 INFO - 'HOME': '/home/cltbld', 11:09:57 INFO - 'LANG': 'en_US.UTF-8', 11:09:57 INFO - 'LANGUAGE': 'en_US:en', 11:09:57 INFO - 'LOGNAME': 'cltbld', 11:09:57 INFO - 'MAIL': '/var/mail/cltbld', 11:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:57 INFO - 'MOZ_NO_REMOTE': '1', 11:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:57 INFO - 'NO_EM_RESTART': '1', 11:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:57 INFO - 'PWD': '/builds/slave/test', 11:09:57 INFO - 'SHELL': '/bin/bash', 11:09:57 INFO - 'SHLVL': '1', 11:09:57 INFO - 'TERM': 'linux', 11:09:57 INFO - 'TMOUT': '86400', 11:09:57 INFO - 'USER': 'cltbld', 11:09:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:57 INFO - '_': '/tools/buildbot/bin/python'} 11:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 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)) 11:09:57 INFO - Cleaning up... 11:09:57 INFO - Return code: 0 11:09:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:57 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:57 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 11:09:57 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 11:09:57 INFO - Using env: {'DISPLAY': ':0', 11:09:57 INFO - 'HOME': '/home/cltbld', 11:09:57 INFO - 'LANG': 'en_US.UTF-8', 11:09:57 INFO - 'LANGUAGE': 'en_US:en', 11:09:57 INFO - 'LOGNAME': 'cltbld', 11:09:57 INFO - 'MAIL': '/var/mail/cltbld', 11:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:57 INFO - 'MOZ_NO_REMOTE': '1', 11:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:57 INFO - 'NO_EM_RESTART': '1', 11:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:57 INFO - 'PWD': '/builds/slave/test', 11:09:57 INFO - 'SHELL': '/bin/bash', 11:09:57 INFO - 'SHLVL': '1', 11:09:57 INFO - 'TERM': 'linux', 11:09:57 INFO - 'TMOUT': '86400', 11:09:57 INFO - 'USER': 'cltbld', 11:09:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:57 INFO - '_': '/tools/buildbot/bin/python'} 11:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:09:57 INFO - Cleaning up... 11:09:57 INFO - Return code: 0 11:09:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:57 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 0x7f3729350f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff4190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', '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 11:09:57 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 11:09:57 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 11:09:57 INFO - Using env: {'DISPLAY': ':0', 11:09:57 INFO - 'HOME': '/home/cltbld', 11:09:57 INFO - 'LANG': 'en_US.UTF-8', 11:09:57 INFO - 'LANGUAGE': 'en_US:en', 11:09:57 INFO - 'LOGNAME': 'cltbld', 11:09:57 INFO - 'MAIL': '/var/mail/cltbld', 11:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:57 INFO - 'MOZ_NO_REMOTE': '1', 11:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:09:57 INFO - 'NO_EM_RESTART': '1', 11:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:57 INFO - 'PWD': '/builds/slave/test', 11:09:57 INFO - 'SHELL': '/bin/bash', 11:09:57 INFO - 'SHLVL': '1', 11:09:57 INFO - 'TERM': 'linux', 11:09:57 INFO - 'TMOUT': '86400', 11:09:57 INFO - 'USER': 'cltbld', 11:09:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:57 INFO - '_': '/tools/buildbot/bin/python'} 11:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:57 INFO - Downloading/unpacking flake8 11:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:09:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:09:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:10: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 11:10: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 11:10: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 11:10: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 11:10: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 11:10: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 11:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:10:00 INFO - no previously-included directories found matching 'docs/_build' 11:10:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:10:00 INFO - Running setup.py install for pep8 11:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:10:00 INFO - no previously-included directories found matching 'docs/_build' 11:10:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:10:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:10:00 INFO - Cleaning up... 11:10:00 INFO - Return code: 0 11:10:00 INFO - Running post-action listener: _resource_record_post_action 11:10:00 INFO - Running post-action listener: _start_resource_monitoring 11:10:00 INFO - Starting resource monitoring. 11:10:00 INFO - ##### 11:10:00 INFO - ##### Finished create-virtualenv step (success) 11:10:00 INFO - ##### 11:10:00 INFO - ##### 11:10:00 INFO - ##### Running install step. 11:10:00 INFO - ##### 11:10:00 INFO - Running pre-action listener: _resource_record_pre_action 11:10:00 INFO - Running main action method: install 11:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:00 INFO - Reading from file tmpfile_stdout 11:10:00 INFO - Detecting whether we're running mozinstall >=1.0... 11:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:10:00 INFO - Reading from file tmpfile_stdout 11:10:00 INFO - Output received: 11:10:00 INFO - Usage: mozinstall [options] installer 11:10:00 INFO - Options: 11:10:00 INFO - -h, --help show this help message and exit 11:10:00 INFO - -d DEST, --destination=DEST 11:10:00 INFO - Directory to install application into. [default: 11:10:00 INFO - "/builds/slave/test"] 11:10:00 INFO - --app=APP Application being installed. [default: firefox] 11:10:00 INFO - mkdir: /builds/slave/test/build/application 11:10:00 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'] 11:10:00 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 11:10:17 INFO - Reading from file tmpfile_stdout 11:10:17 INFO - Output received: 11:10:17 INFO - /builds/slave/test/build/application/firefox/firefox 11:10:17 INFO - Running post-action listener: _resource_record_post_action 11:10:17 INFO - ##### 11:10:17 INFO - ##### Finished install step (success) 11:10:17 INFO - ##### 11:10:17 INFO - ##### 11:10:17 INFO - ##### Running run-tests step. 11:10:17 INFO - ##### 11:10:17 INFO - Running pre-action listener: _resource_record_pre_action 11:10:17 INFO - Running main action method: run_tests 11:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:10:17 INFO - Python 2.7.3 11:10:17 INFO - Return code: 0 11:10:17 INFO - grabbing minidump binary from tooltool 11:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:17 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 0x2086110>, '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 0x1ff2230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff26c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:10:17 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 11:10:17 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 11:10:17 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 11:10:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:10:18 INFO - Return code: 0 11:10:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:10:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:10:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:10:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:10:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:10:18 INFO - Return code: 0 11:10:18 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:10:18 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:10:18 INFO - Using env: {'DISPLAY': ':0', 11:10:18 INFO - 'HOME': '/home/cltbld', 11:10:18 INFO - 'LANG': 'en_US.UTF-8', 11:10:18 INFO - 'LANGUAGE': 'en_US:en', 11:10:18 INFO - 'LOGNAME': 'cltbld', 11:10:18 INFO - 'MAIL': '/var/mail/cltbld', 11:10:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:10:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:18 INFO - 'MOZ_NO_REMOTE': '1', 11:10:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:10:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:18 INFO - 'NO_EM_RESTART': '1', 11:10:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:18 INFO - 'PWD': '/builds/slave/test', 11:10:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:10:18 INFO - 'SHELL': '/bin/bash', 11:10:18 INFO - 'SHLVL': '1', 11:10:18 INFO - 'TERM': 'linux', 11:10:18 INFO - 'TMOUT': '86400', 11:10:18 INFO - 'USER': 'cltbld', 11:10:18 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393', 11:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:18 INFO - '_': '/tools/buildbot/bin/python'} 11:10:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:10:19 INFO - mozversion application_buildid: 20160621083052 11:10:19 INFO - mozversion application_changeset: ecd3562339dcd51a604e82efbcb347de8b823aee 11:10:19 INFO - mozversion application_display_name: Nightly 11:10:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:10:19 INFO - mozversion application_name: Firefox 11:10:19 INFO - mozversion application_remotingname: firefox 11:10:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:10:19 INFO - mozversion application_vendor: Mozilla 11:10:19 INFO - mozversion application_version: 50.0a1 11:10:19 INFO - mozversion platform_buildid: 20160621083052 11:10:19 INFO - mozversion platform_changeset: ecd3562339dcd51a604e82efbcb347de8b823aee 11:10:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:10:19 INFO - mozversion platform_version: 50.0a1 11:10:19 INFO - using testdate: 1466532619 11:10:19 INFO - actual date: 1466532619 11:10:19 INFO - starting webserver on 'localhost:59118' 11:10:19 INFO - SUITE-START | Running 2 tests 11:10:19 INFO - TEST-START | dromaeo_css 11:10:19 INFO - operating with platform_type : linux_ 11:10:19 INFO - Initialising browser for dromaeo_css test... 11:10:19 INFO - TEST-INFO | started process 16766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYyxb3x/profile http://localhost:59118/getInfo.html) 11:10:23 INFO - PROCESS | 16766 | __metrics Screen width/height:1600/1200 11:10:23 INFO - PROCESS | 16766 | colorDepth:24 11:10:23 INFO - PROCESS | 16766 | Browser inner width/height: 1024/697 11:10:23 INFO - PROCESS | 16766 | __metrics 11:10:24 ERROR - PROCESS | 16766 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 11:10:25 INFO - TEST-INFO | 16766: exit 0 11:10:25 INFO - Browser initialized. 11:10:25 INFO - Running cycle 1/1 for dromaeo_css test... 11:10:25 INFO - TEST-INFO | started process 16886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYyxb3x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:37 INFO - PROCESS | 16886 | RSS: Main: 185106432 11:10:37 INFO - PROCESS | 16886 | 11:11:27 INFO - PROCESS | 16886 | 1466532687127 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] 11:13:35 INFO - PROCESS | 16886 | Cycle 1(1): loaded http://localhost:59118/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59118/tests/dromaeo/cssquery-ext.html) 11:13:36 INFO - PROCESS | 16886 | RSS: Main: 188588032 11:13:36 INFO - PROCESS | 16886 | 11:16:38 INFO - PROCESS | 16886 | Cycle 1(1): loaded http://localhost:59118/tests/dromaeo/cssquery-ext.html (next: http://localhost:59118/tests/dromaeo/cssquery-jquery.html) 11:16:39 INFO - PROCESS | 16886 | RSS: Main: 187969536 11:16:39 INFO - PROCESS | 16886 | 11:19:40 INFO - PROCESS | 16886 | Cycle 1(1): loaded http://localhost:59118/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59118/tests/dromaeo/cssquery-mootools.html) 11:19:41 INFO - PROCESS | 16886 | RSS: Main: 189153280 11:19:41 INFO - PROCESS | 16886 | 11:22:43 INFO - PROCESS | 16886 | Cycle 1(1): loaded http://localhost:59118/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59118/tests/dromaeo/cssquery-prototype.html) 11:22:43 INFO - PROCESS | 16886 | RSS: Main: 186736640 11:22:43 INFO - PROCESS | 16886 | 11:25:46 INFO - PROCESS | 16886 | Cycle 1(1): loaded http://localhost:59118/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59118/tests/dromaeo/cssquery-yui.html) 11:25:46 INFO - PROCESS | 16886 | RSS: Main: 189095936 11:25:46 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | Cycle 1(1): loaded http://localhost:59118/tests/dromaeo/cssquery-yui.html (next: http://localhost:59118/tests/dromaeo/cssquery-dojo.html) 11:28:50 INFO - PROCESS | 16886 | RSS: Main: 189681664 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | __start_tp_report 11:28:50 INFO - PROCESS | 16886 | _x_x_mozilla_page_load 11:28:50 INFO - PROCESS | 16886 | _x_x_mozilla_page_load_details 11:28:50 INFO - PROCESS | 16886 | |i|pagename|runs| 11:28:50 INFO - PROCESS | 16886 | |0;dojo.html;3129.9374012519734;3139.073973177912;3181.45915194417;3185.299234168482;3189.027346659268;5358.651687640306;5384.219288203209;5404.594655400846;5456.945430545693;5505.449455054492;5333.946660533394;5349.358077030752;5373.6775793452325;5376.892462150755;5405.027095122886;4860.708357498544;4885.120678277908;4972.627052971024;5018.874528136789;5068.518490743392;691.5998024607445;4919.655624106314;4960.156773348522;4975.875603109933;5002.149634562115;4902.877428064291;4904.901901961976;4905.117078925774;4921.901561968759;4990.6257030722845;4794.9520504794855;4938.185199442088;4948.1093403187415;4970;5020.723860187688;4830.903381932341;4927.359443272351;4937.35814344133;4978.751062446863;4981.103401387749;5439.265699130646;5441.646292990943;5445.101558242885;5473.644213126174;5475.972620136874;5703;5849.064149736065;5853.824385268449;5861.150133230658;5921.111833225008;5770.394108618619;5816.331121920991;5821.621594596338;5858.150568167592;5862.267216597926;5437.6465529740835;5439.184122381635;5449.591280653967;5460.31746031746;5465.316835395575;5651.350094739076;5700.087985922232;5903.3801450847905;5911.881762364728;5918.46733793952;5591.245181900392;5594.468525490072;5632.014397480424;5647.548196144298;5722.427757224327;5675.858103547444;5720.456556627197;5722.971385143131;5731.996900542388;5753.4821866031425;5612.298462692163;5640.774369025193;5685.943140568624;5708.229389032511;5733.082706766897;5793.507551858139;5836.737346819404;5841.094630332221;5877.52979761618;5880.353161152269;4400.273954797487;4460.7323560586465;4463.464384273908;4475.373447717322;4502.482214545369;3923.195744872294;3926.2932672118723;3942.3692209246956;3995.6603688686787;4033.1328764315226;5503.3395992481155;5540.335159780852;5552.69460179694;5566.860828479239;5630.85922851932;5837.066069428956;5843.532517398683;5851.034579294454;5855.443732845373;5857.326407463111;5393.892122157535;5398.190271459234;5435.537979271727;5543.113101903676;5556.055470570012;4897.216445368796;4917.7786999585105;4922.483139270396;4922.9753851231235;4934.8026078957155;5853.560982926298;5906.793262235801;5911.556633252523;5927.347991720907;5929.495992840571;5724.8568785779025;5725.77096916127;5741.167530708108;5801.535877129904;5806.709664516842;5915.378885217162;5921.940780592311;5937.851553711192;5938.910916336345;5948.8810223796145;5583.525400341016;5636.971815140898;5637.718114094361;5640.576956728344;5658.122990936411;5641.830745077655;5723.227364305765;5765.192872997867;5801.245838041028;5808.477237048686;5612.466815652507;5639.351474580476;5683.943160568341;5704.172894930129;5708;5673.517750991212;5690.231818704422;5691.317041954826;5692.7722891083895;5711.771529138788;5455.809046683347;5502.724863756716;5506.889862202654;5537;5554.527865131509;5644.887102258014;5650.463205995424;5658.236138121301;5663.461971112738;5667.4615911488345;5452.7546260417585;5508.394076651645;5542.0855558833955;5551.750171242385;5565.777368905198 11:28:50 INFO - PROCESS | 16886 | |1;ext.html;545.725396880024;588.3028611095851;601.2844714789402;602.4036204157879;605.3492495567266;41349.379759303636;42697.78651106744;42816.357754633704;42850.142997140036;46947;43918.780406098005;44594;44595;44749.776251118696;44976.77511612437;8909.109089091104;9095.727128186132;9110;9112.134347237003;9134.634614615408;8945.507997060191;8978.461292322481;8997.145271199226;9009.684661036865;9010.819783604324;8987.910120898805;9030.638774449015;9052.954735226314;9069.319801014917;9071.637134514645;8876.600552975131;8934.329925255599;8941.329400294971;8951.955240223757;8958.820823583492;8549.273311768507;8554.272886804567;8616.095309992486;8625.56872156396;8626.913730862736;41263.38104928428;41667.37498937518;41746.58253417457;41811;41852.790736046125;3540.5574303212097;4080.9389558714947;4083.1221287423346;4143.606357396043;4295.763732994684;31790.682093179003;32373.38133093336;32421.67578324234;32426.18934526632;32755.50866737001;4133.111381053058;4231.946068310163;4254.42565253693;4274.123804620045;4283.378910058024;337723;339377;340805;341139.2943035294;341165;54281.72859135679;54685.45314546803;54686;54709.72645136749;55578;32983.50524742131;48080.5191948083;48593.27110093351;48688.26967595489;48906.5109348902;15144.848551514564;16064.598385040468;16091.275892584628;16114;16119.67760644804;11075;11091.611793587186;11104.888951110386;11106.111511079094;11128.38793866345;18411.53971150732;18597.7210341845;18634.347797827282;18717.532061698566;18718.53203669919;6857.245702972669;7134.500584959107;7242.565446073252;7259.346658800628;7314.524555903849;923.6859467780987;1024.329064462778;1028.4138041316528;1100.4772732951944;1102.415719668577;32123.839380802947;32345.838270808494;32912.67087329096;32956;33354.166145846066;1082.9350238985687;1109.5617231193633;1171.5196769324536;1211.2127117373602;1216.7688139253514;16499.340026398808;16739.916300418663;16790.74813877793;16938.661226775395;17342.219600118024;586196;586684;586765;587941;590901;56831.715841420526;57460.71269643625;57472.42527574754;57628.42371576231;57774.133387999216;1253.6677780388204;1314.7764879970428;1330.6340756292097;1333.9333033348294;1338.7255612599395;30813.22966925845;31683.683163168997;32130.83934580312;32243.193920152185;32282.677173227967;49741;49741.751291243316;49847.252291216384;49979.50020499749;50156.247656285916;422692;428163;430422;431924;433538;27450;28010.2997425066;28025.299367515974;28044;28059;46715.766421167675;48337.51662483471;49152.75423622859;52238.21642675439;52960.735196323774;40118.799406002785;40799.38800918048;41330.38004429996;42238.57761422346;42714;40492.3926141114;40534.59465405308;40565.59434405618;41183.7940810294;41848.163036738486;40037;41461.79269103635;42536.78731606322;42707;42707.35938960856;34925.30149396947;35932.820335898155;36944.81527592345;37037.25925481529;37085.443718343704;27777.44445111127;28630.713692862806;28734.71265287404;28961.56557651679;29205.561916570845 11:28:50 INFO - PROCESS | 16886 | |2;jquery.html;363.1031352559179;366.94312381580846;368.61847987333107;373.9556694350598;378.77462909568794;17815.10924453777;18461.446156615308;18820.435386938396;19164.233430662793;19267.421977340666;17926.82073179268;17953.28186872527;18038.909805450956;18975.62048759024;18976.146073426695;5584.4136365681525;5734.483896449318;5760.53915686744;5789.363170051312;5796.536277097843;5554.222408862763;5594.916076258839;5657.2928383952;5675.5459563234845;5696.316442026942;5630.859228519299;5635.943640563603;5645.8588535286535;5653;5675.6878371689545;5617.522510586605;5636.859078523029;5649.406812284713;5665.49010589049;5674.3190817101795;4981.72600506972;4984.252362145708;5008.524190201925;5017.147084995523;5028.094942910275;21424.25015124479;22162.11351545936;22245.11019559216;22322;22509.212177573707;693.2028167607242;693.9410150137245;947.6351604632197;1997.6304383688919;2327.1156960354956;13406.262655553941;13985.510507132201;14122.505712300122;14232.715345693132;14501.637459063502;1914.712793081035;1970.4975231315923;1990.0004950250075;1992.721019057333;2019.7677267114323;67624.3237567623;75915.62042189809;82859;84082.57958710167;84089.99999999939;9978.401295922267;10581.62964296246;10605.204609654307;10912.127029837595;10923.289986150874;14820.925895370454;14876.851231487763;14890;14902.70194596102;14932.104073755392;7454.440916931252;7509.51188172778;7552.962235188789;7630.8092297693;7638.312551870358;7124.715011399486;7225.927740722525;7238.0952380952385;7349.926500735031;7715.537067775951;4450.821967121279;4513.774311284423;4575.862724118282;4627.8611641650805;4634.420697412824;1830.1855674225108;1833.7432759413693;1858.1823997441352;1859.869809113376;1860.2280053777802;850.2475309351269;976.1800087926176;1011.2819897872446;1091.5033659684825;1107.1198397274186;17017.404390846263;18456;18590.721139182922;19211;19212.327568535035;948.0472125513814;950.9239260859115;968.6464440479174;973.1290495006929;978.6427953796813;10165.28842980999;10240.48797560119;10400.375977441377;10641.5211315491;10753.085987691135;376096;382543;385327;496814;544168;93066;94315.52842235746;95879;97243.51378243063;98265;992.8262554053011;1019.9847002294972;1038.2991480750463;1044.7805960748328;1053.9789204215872;16497.257623406735;17780.822191778436;17830.197641106442;17911.10444477797;18075.638487230444;40336.3949540763;41752;42079.36880946727;42219.3667095;43333.34999975066;59275.40724592699;59304.40695592989;60453;61402.38597613967;61597.38402616096;8378.120297368689;8428.283595894172;8534.103919088406;8567.27178189861;8651.394402391772;11887.46506407231;13172.934135329262;13305.667358315732;13477.32613369308;13546;11789.941050294694;11897.940510297392;11920.463579139134;11927.82108268394;12255.877441225473;11753.236039657395;11825;11914.880851191723;11961.282323060645;12074.818877716665;11328.660140195807;12131;12215.816762748744;12217.69455763613;12223.44994475233;8216.09622941488;8380.329573634217;8405.915940840514;8442.788930276793;8477.576121193792;11426.2001659883;11453.713657158638;11466.541338346706;11754.23597466162;12077.51689932393 11:28:50 INFO - PROCESS | 16886 | |3;mootools.html;1369.1442848219863;1413.4558195094885;1531.4869518711232;1690.4928521443564;1697.3550050980625;7736.806579835507;8244.876326855101;8271.421000529957;8750.08124146965;8761.868571971418;8287.502749835;8557.657693692261;8577.14228577142;8631.266342360892;8641.092685268051;2260.4235919840416;2293.81649468043;2317.97177469714;2318.026428899866;2318.3508617587104;2242.9663555046686;2250.8086812620945;2291.461506545969;2295.1966811615966;2298.8275879309035;2243.7756224377595;2267.1724820440504;2281.851679640818;2283.3720726800098;2285.462916214688;2169.6962425260476;2171.7285339332584;2176.096919778298;2178.400939741568;2191.9561608767895;2148.98925505373;2187.7155969723995;2194.4294483434264;2195.5169862630196;2198.7801219878043;8745.300375969908;8790.164934331293;8791.956040219759;8819.162179592928;8822.867656985087;4939.753012349404;5018.498150184989;5021.447640759514;5064.772085256135;5080.542751152414;3135.7961732487543;3216.6300875399393;3344.4314466540504;3367.478040903664;3379.4423920053164;1139.960101396447;1144.8855114488567;1155.9711007224803;1156.6009726644295;1170.2101081769772;12160.817587736192;12198.634040978784;12206.145569810116;12224.755504889941;12232.510699572007;4405.185040767468;4441.822327106879;4513.029698614748;4518.277075667877;4528.298113792367;10207.9489602553;10258.897411025795;10266.384016959006;10286.639967601099;10353.327033742782;4991.376077990251;5000.549950504399;5039.4708555601865;5079.425378132791;5087.516685914832;4162.334026555736;4514.210013247734;4611.123886461562;4684.882877928011;4691.202495575761;3741.4761933329355;3773.5471743390967;3783.848646054182;3834.1564855731694;3984.561698213194;1198.5924785572947;1218.993905030487;1232.3037483821613;1239.6652903715947;1243.830799048891;823.4400607586779;892.2549671024638;915.199200699388;926.995365023184;935.4434111703525;5835.270591176103;5876.382979787147;5958.6424814511265;6120.265568131853;6287.339829317947;944.607987685103;949.4635530925143;954.4511905654276;956.990430095704;969.8981606931312;4051.554329023805;4202.180574787948;4281.828726850891;4314.460692413449;4317.503487098961;10630.52162652667;10891.727706807393;10963.780724385468;10987.285826421257;10998.450077496253;10528;10784.299020563638;10843.240973131962;10886.129109671207;10949.671509854717;7806.648700808477;7833.785763206712;7896.881546776803;7957.6021198939825;8025.678972841138;5891.293044834647;6104.084387341934;6133.877322453437;6240.656763878031;6288.245410550763;9671.9032809671;9841.507924603884;9926.751831203988;10011.599536018477;10144.442055687008;10198.643047493302;10271.126954208976;10307.484625768531;10325;10328.225383096147;7822.530648161129;7890.9605451972375;8005.279524842792;8067.515949042841;8076.030876294883;8130.75607731769;8136.145704701158;8198.754037378889;8206.179382061984;8240.299574535991;7468.5145465544565;7692.115406728298;7761.301482866569;7771.339436147765;7782.766517004499;7192.352688258082;7214.350708436266;7282.1625513064555;7334.633268336668;7475.140358858801;6912.861742765217;6921.480888933451;6974.965125174342;6993.930060699327;7036.894446583203;5081.11080560893;5090.778213228725;5227.398849132246;5283.233931079895;5340.225667278145;4927.556519913225;5164.715940623302;5186.58507319406;5266.210068489757;5319.893602128013 11:28:50 INFO - PROCESS | 16886 | |4;prototype.html;290.59897341668494;296.8619591889774;300.17751361270126;311.1862479615805;313.57353998561956;5634.40838711935;5653.434656534345;5687.772489100442;5689.203511508382;5695.430456954303;5553.750081246343;5579.107342825138;5584.1902924075985;5589.7205139743055;5598.524125449331;4044.5348784889748;4140.171965606875;4253.595908388714;4258.212230737324;4281.828726850922;4221.936670949938;4240.830366785325;4255.765932873691;4263.59495847894;4272.957270427302;4215.915681686364;4229.830806767741;4235.68232382571;4239.745615263094;4246.851360202393;3995.2209319182916;4179.331306990897;4193.553224137698;4199.328107502785;4230.492340919079;3616.27674465108;3634.963650363489;3658.32321020612;3664.358737220976;3673.338799016176;5386.892262154735;5450.618456708032;5452.182172674091;5480.972595137038;5485.75314110862;4567.9086418271445;4825.348577941968;4825.4933232010835;4866.635002374836;4872.756362181876;5838.737256823453;5844.707764611752;5859.121131830217;5878.559108066912;5881.323647780518;4006.777932730516;4030.6976976726864;4033.882211838779;4035.233305671913;4045.8583949561917;6006.8498287542725;6040.124181993587;6087.360827113133;6099.085137229406;6111.480524155452;3969.0871099647243;3974.4634474345594;3984.621460961204;4020.6984476164403;4031.294523458383;4004.539477960072;4015.477987861559;4043.8786836394956;4051.817668204879;4053.108316170438;4597.954020459752;4701.717896926195;4727.581725482411;4758.643101767381;4770.880727981828;5820.738066787005;5828.446297601721;5884.558658100659;5884.705764711747;5887.264091988502;1415.3277193333086;1434.2040167706814;1434.2326855132453;1439.9064060836013;1447.486142419426;876.6975393489236;896.5786080542135;898.6899519665704;920.6869664314165;960.4573416019927;5508.862278442991;5542.695151766691;5578.888422231533;5587.636803607753;5623.606347555632;5837.795677151279;5863.5309175265875;5868.413158684183;5869.383714709979;5888.793892213837;5340.359156901197;5536.612437129443;5561.777528898799;5593.412691667398;5660.688662123623;3161.604799400075;3178.1260153457984;3182.840857957093;3193.680631936788;3198.2484116232667;5826.388229235954;6029.427204415573;6107.267127944675;6130.743197644472;6169.629822210682;5995.850103747354;6012.609180403348;6026;6026.638401695912;6028.367021462771;6001.319630503466;6008.699565021731;6060.424259695322;6100.712232912658;6136.465883529207;5756.280464941882;5934.258217722785;5948.821535353946;5950.880982380415;5968.970155149196;5863;5883.705814709162;5903.911441328298;5919.822405327847;5921.555883308855;5997.320750189954;6011.789587364421;6093.756249750138;6093.969530152321;6111.449969502766;4110.712250142461;4131.772752498641;4133.379993001074;4141.91716165681;4152.979235103926;5914.290285165807;5968.432998865101;5993.490553303018;6005.39946005396;6020.759169633166;5422.9310242099455;5691.373948865705;5701.6316084140335;5759.9136012959;5785.18588818235;5624.634398764068;5662.745176467151;5720.799772007959;5786.755847492809;5807.486470685343;5434.945650543444;5533.253010843646;5591.133374326986;5643.830685079454;5655.575831812713;5780.468175933297;5793.710314484343;5798.477662216949;5810.82567522975;5810.912836307374;5561.221429000024;5651.237082993907;5655.7840064386355;5668.234788303526;5680.7443665036 11:28:50 INFO - PROCESS | 16886 | |5;yui.html;681.3697329969776;725.2058995400035;725.8874874394471;732.714241445836;755.2636179724769;13476.124051936617;13601.727965440685;13718.657033574165;13876.58370248893;14973.176475293854;13168.670783230424;13379.331033448338;13453.125546839448;13540.661483462894;13552.18686878788;725.0429433148247;766.1380946435246;780.2314720000822;782.636074225485;783.2363445640511;762.9585615634675;790.8378782349633;791.2601717394255;796.6056801883076;799.8800179973022;828.4325237212498;830.9293710034655;833.2584000239791;835.5947365527715;845.9323254139684;844.7507985144397;845.2097289034741;846.3567688556741;847.7117779954814;850.5619605902965;598.4703537369415;601.1553766957431;605.7758629307141;606.4451027310006;613.8434699151733;15367.846321536865;15440.45958391451;15440.536783896388;15484.690306193927;15688.92155539215;13836.930815345959;13999.720005599933;14298.785518217235;14587.489437869624;14642.41430342785;12148.878511214863;12335.136540442083;12441.875581244161;12528.561500347536;12574.11981161319;3797.411401232813;3806.26611144158;3898.3567711327596;3934.075492259317;3937.625925537069;78845;79702.60148699277;79980.60009699973;80382;81343.18656813416;10705.982931621484;10725.14198864089;11074.612388566362;11177.99398054179;11370.204085714086;18323.54191145232;20145.09347079385;20289;20350;20414;8407.873881891777;8540.10328915455;8616;8700.34747393948;8851.955740221387;4606.539346065434;4668.603168730696;4694.201985662446;4775.95224047762;4874.147024270738;3418.7094097001536;3471.7222622190166;3518.595361533405;3531.29374125176;3537.168765340143;589.163387989048;621.4841681404423;628.7767842415968;637.4454224824433;643.8261669349249;667.8063361625075;712.8629835412509;716.3266529462289;730.0071902212986;753.9698412063456;9444.48055356962;9473.242140628734;9547.90452095484;9656.75858103553;9672.322937394314;694.465261748461;702.4836744992426;714.242902523327;725.2674798453495;743.7211045857804;4406.5593440655675;4442.844500442534;4477.440319960005;4533.77331133442;4631.814727410933;67966.66016669886;106734.46632766786;107440;108072;108734;92761.60857587142;93972.53013734888;94374.52812735892;94407.52796235975;94776.52611737035;79530.20469795344;80874;84022;85404;86374.56812715896;8524.201540890399;8550.35872309567;8656.697015604424;8756.781080472785;9007.189352958265;46181.76909115433;51028.74485627548;51042;51733.22400164076;53209.733951329996;66996.66501667461;68150.65924670345;68312;68965.31034689589;72170;12296.262224266573;12418.820212079838;12865.2924089176;13240.27178505153;13371.866281337063;14515.92742036283;14579.125252484884;15295.184203709994;15445.84554154444;15524.611884702972;14149.575512734635;14611.269436527919;14757.557273281818;15020.624484387978;15037.624059398602;13549.86450135486;13595.932020339835;13626.250556219504;13764.862351376358;14230.501932432315;13901.165930043826;13945.721085578434;13963.930180349033;14315.785263220852;14343.354549045109;10137.493125343852;10194.286399951932;10244.487775611338;10446.268761186644;10520.473976301308;9775.706728798148;11365.374904380007;11488.094048023711;11508.49031771038;11656 11:28:50 INFO - PROCESS | 16886 | __end_tp_report 11:28:50 INFO - PROCESS | 16886 | __start_cc_report 11:28:50 INFO - PROCESS | 16886 | _x_x_mozilla_cycle_collect,356 11:28:50 INFO - PROCESS | 16886 | __end_cc_report 11:28:50 INFO - PROCESS | 16886 | __startTimestamp1466533730270__endTimestamp 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | ------- Summary: start ------- 11:28:50 INFO - PROCESS | 16886 | Number of tests: 6 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | [#0] dojo.html Cycles:165 Average:5343.37 Median:5566.86 stddev:695.98 (12.5%) stddev-sans-first:676.11 11:28:50 INFO - PROCESS | 16886 | Values: 3129.9 3139.1 3181.5 3185.3 3189.0 5358.7 5384.2 5404.6 5456.9 5505.4 5333.9 5349.4 5373.7 5376.9 5405.0 4860.7 4885.1 4972.6 5018.9 5068.5 691.6 4919.7 4960.2 4975.9 5002.1 4902.9 4904.9 4905.1 4921.9 4990.6 4795.0 4938.2 4948.1 4970.0 5020.7 4830.9 4927.4 4937.4 4978.8 4981.1 5439.3 5441.6 5445.1 5473.6 5476.0 5703.0 5849.1 5853.8 5861.2 5921.1 5770.4 5816.3 5821.6 5858.2 5862.3 5437.6 5439.2 5449.6 5460.3 5465.3 5651.4 5700.1 5903.4 5911.9 5918.5 5591.2 5594.5 5632.0 5647.5 5722.4 5675.9 5720.5 5723.0 5732.0 5753.5 5612.3 5640.8 5685.9 5708.2 5733.1 5793.5 5836.7 5841.1 5877.5 5880.4 4400.3 4460.7 4463.5 4475.4 4502.5 3923.2 3926.3 3942.4 3995.7 4033.1 5503.3 5540.3 5552.7 5566.9 5630.9 5837.1 5843.5 5851.0 5855.4 5857.3 5393.9 5398.2 5435.5 5543.1 5556.1 4897.2 4917.8 4922.5 4923.0 4934.8 5853.6 5906.8 5911.6 5927.3 5929.5 5724.9 5725.8 5741.2 5801.5 5806.7 5915.4 5921.9 5937.9 5938.9 5948.9 5583.5 5637.0 5637.7 5640.6 5658.1 5641.8 5723.2 5765.2 5801.2 5808.5 5612.5 5639.4 5683.9 5704.2 5708.0 5673.5 5690.2 5691.3 5692.8 5711.8 5455.8 5502.7 5506.9 5537.0 5554.5 5644.9 5650.5 5658.2 5663.5 5667.5 5452.8 5508.4 5542.1 5551.8 5565.8 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | [#1] ext.html Cycles:180 Average:60691.18 Median:42045.68 stddev:123323.11 (293.3%) stddev-sans-first:123586.38 11:28:50 INFO - PROCESS | 16886 | Values: 545.7 588.3 601.3 602.4 605.3 41349.4 42697.8 42816.4 42850.1 46947.0 43918.8 44594.0 44595.0 44749.8 44976.8 8909.1 9095.7 9110.0 9112.1 9134.6 8945.5 8978.5 8997.1 9009.7 9010.8 8987.9 9030.6 9053.0 9069.3 9071.6 8876.6 8934.3 8941.3 8952.0 8958.8 8549.3 8554.3 8616.1 8625.6 8626.9 41263.4 41667.4 41746.6 41811.0 41852.8 3540.6 4080.9 4083.1 4143.6 4295.8 31790.7 32373.4 32421.7 32426.2 32755.5 4133.1 4231.9 4254.4 4274.1 4283.4 337723.0 339377.0 340805.0 341139.3 341165.0 54281.7 54685.5 54686.0 54709.7 55578.0 32983.5 48080.5 48593.3 48688.3 48906.5 15144.8 16064.6 16091.3 16114.0 16119.7 11075.0 11091.6 11104.9 11106.1 11128.4 18411.5 18597.7 18634.3 18717.5 18718.5 6857.2 7134.5 7242.6 7259.3 7314.5 923.7 1024.3 1028.4 1100.5 1102.4 32123.8 32345.8 32912.7 32956.0 33354.2 1082.9 1109.6 1171.5 1211.2 1216.8 16499.3 16739.9 16790.7 16938.7 17342.2 586196.0 586684.0 586765.0 587941.0 590901.0 56831.7 57460.7 57472.4 57628.4 57774.1 1253.7 1314.8 1330.6 1333.9 1338.7 30813.2 31683.7 32130.8 32243.2 32282.7 49741.0 49741.8 49847.3 49979.5 50156.2 422692.0 428163.0 430422.0 431924.0 433538.0 27450.0 28010.3 28025.3 28044.0 28059.0 46715.8 48337.5 49152.8 52238.2 52960.7 40118.8 40799.4 41330.4 42238.6 42714.0 40492.4 40534.6 40565.6 41183.8 41848.2 40037.0 41461.8 42536.8 42707.0 42707.4 34925.3 35932.8 36944.8 37037.3 37085.4 27777.4 28630.7 28734.7 28961.6 29205.6 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | [#2] jquery.html Cycles:180 Average:27568.24 Median:371.29 stddev:73420.32 (19774.5%) stddev-sans-first:73597.87 11:28:50 INFO - PROCESS | 16886 | Values: 363.1 366.9 368.6 374.0 378.8 17815.1 18461.4 18820.4 19164.2 19267.4 17926.8 17953.3 18038.9 18975.6 18976.1 5584.4 5734.5 5760.5 5789.4 5796.5 5554.2 5594.9 5657.3 5675.5 5696.3 5630.9 5635.9 5645.9 5653.0 5675.7 5617.5 5636.9 5649.4 5665.5 5674.3 4981.7 4984.3 5008.5 5017.1 5028.1 21424.3 22162.1 22245.1 22322.0 22509.2 693.2 693.9 947.6 1997.6 2327.1 13406.3 13985.5 14122.5 14232.7 14501.6 1914.7 1970.5 1990.0 1992.7 2019.8 67624.3 75915.6 82859.0 84082.6 84090.0 9978.4 10581.6 10605.2 10912.1 10923.3 14820.9 14876.9 14890.0 14902.7 14932.1 7454.4 7509.5 7553.0 7630.8 7638.3 7124.7 7225.9 7238.1 7349.9 7715.5 4450.8 4513.8 4575.9 4627.9 4634.4 1830.2 1833.7 1858.2 1859.9 1860.2 850.2 976.2 1011.3 1091.5 1107.1 17017.4 18456.0 18590.7 19211.0 19212.3 948.0 950.9 968.6 973.1 978.6 10165.3 10240.5 10400.4 10641.5 10753.1 376096.0 382543.0 385327.0 496814.0 544168.0 93066.0 94315.5 95879.0 97243.5 98265.0 992.8 1020.0 1038.3 1044.8 1054.0 16497.3 17780.8 17830.2 17911.1 18075.6 40336.4 41752.0 42079.4 42219.4 43333.3 59275.4 59304.4 60453.0 61402.4 61597.4 8378.1 8428.3 8534.1 8567.3 8651.4 11887.5 13172.9 13305.7 13477.3 13546.0 11789.9 11897.9 11920.5 11927.8 12255.9 11753.2 11825.0 11914.9 11961.3 12074.8 11328.7 12131.0 12215.8 12217.7 12223.4 8216.1 8380.3 8405.9 8442.8 8477.6 11426.2 11453.7 11466.5 11754.2 12077.5 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | [#3] mootools.html Cycles:180 Average:5722.81 Median:4688.04 stddev:3274.43 (69.8%) stddev-sans-first:3267.27 11:28:50 INFO - PROCESS | 16886 | Values: 1369.1 1413.5 1531.5 1690.5 1697.4 7736.8 8244.9 8271.4 8750.1 8761.9 8287.5 8557.7 8577.1 8631.3 8641.1 2260.4 2293.8 2318.0 2318.0 2318.4 2243.0 2250.8 2291.5 2295.2 2298.8 2243.8 2267.2 2281.9 2283.4 2285.5 2169.7 2171.7 2176.1 2178.4 2192.0 2149.0 2187.7 2194.4 2195.5 2198.8 8745.3 8790.2 8792.0 8819.2 8822.9 4939.8 5018.5 5021.4 5064.8 5080.5 3135.8 3216.6 3344.4 3367.5 3379.4 1140.0 1144.9 1156.0 1156.6 1170.2 12160.8 12198.6 12206.1 12224.8 12232.5 4405.2 4441.8 4513.0 4518.3 4528.3 10207.9 10258.9 10266.4 10286.6 10353.3 4991.4 5000.5 5039.5 5079.4 5087.5 4162.3 4514.2 4611.1 4684.9 4691.2 3741.5 3773.5 3783.8 3834.2 3984.6 1198.6 1219.0 1232.3 1239.7 1243.8 823.4 892.3 915.2 927.0 935.4 5835.3 5876.4 5958.6 6120.3 6287.3 944.6 949.5 954.5 957.0 969.9 4051.6 4202.2 4281.8 4314.5 4317.5 10630.5 10891.7 10963.8 10987.3 10998.5 10528.0 10784.3 10843.2 10886.1 10949.7 7806.6 7833.8 7896.9 7957.6 8025.7 5891.3 6104.1 6133.9 6240.7 6288.2 9671.9 9841.5 9926.8 10011.6 10144.4 10198.6 10271.1 10307.5 10325.0 10328.2 7822.5 7891.0 8005.3 8067.5 8076.0 8130.8 8136.1 8198.8 8206.2 8240.3 7468.5 7692.1 7761.3 7771.3 7782.8 7192.4 7214.4 7282.2 7334.6 7475.1 6912.9 6921.5 6975.0 6993.9 7036.9 5081.1 5090.8 5227.4 5283.2 5340.2 4927.6 5164.7 5186.6 5266.2 5319.9 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | [#4] prototype.html Cycles:180 Average:4829.88 Median:5592.27 stddev:1459.77 (26.1%) stddev-sans-first:1423.55 11:28:50 INFO - PROCESS | 16886 | Values: 290.6 296.9 300.2 311.2 313.6 5634.4 5653.4 5687.8 5689.2 5695.4 5553.8 5579.1 5584.2 5589.7 5598.5 4044.5 4140.2 4253.6 4258.2 4281.8 4221.9 4240.8 4255.8 4263.6 4273.0 4215.9 4229.8 4235.7 4239.7 4246.9 3995.2 4179.3 4193.6 4199.3 4230.5 3616.3 3635.0 3658.3 3664.4 3673.3 5386.9 5450.6 5452.2 5481.0 5485.8 4567.9 4825.3 4825.5 4866.6 4872.8 5838.7 5844.7 5859.1 5878.6 5881.3 4006.8 4030.7 4033.9 4035.2 4045.9 6006.8 6040.1 6087.4 6099.1 6111.5 3969.1 3974.5 3984.6 4020.7 4031.3 4004.5 4015.5 4043.9 4051.8 4053.1 4598.0 4701.7 4727.6 4758.6 4770.9 5820.7 5828.4 5884.6 5884.7 5887.3 1415.3 1434.2 1434.2 1439.9 1447.5 876.7 896.6 898.7 920.7 960.5 5508.9 5542.7 5578.9 5587.6 5623.6 5837.8 5863.5 5868.4 5869.4 5888.8 5340.4 5536.6 5561.8 5593.4 5660.7 3161.6 3178.1 3182.8 3193.7 3198.2 5826.4 6029.4 6107.3 6130.7 6169.6 5995.9 6012.6 6026.0 6026.6 6028.4 6001.3 6008.7 6060.4 6100.7 6136.5 5756.3 5934.3 5948.8 5950.9 5969.0 5863.0 5883.7 5903.9 5919.8 5921.6 5997.3 6011.8 6093.8 6094.0 6111.4 4110.7 4131.8 4133.4 4141.9 4153.0 5914.3 5968.4 5993.5 6005.4 6020.8 5422.9 5691.4 5701.6 5759.9 5785.2 5624.6 5662.7 5720.8 5786.8 5807.5 5434.9 5533.3 5591.1 5643.8 5655.6 5780.5 5793.7 5798.5 5810.8 5810.9 5561.2 5651.2 5655.8 5668.2 5680.7 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - PROCESS | 16886 | [#5] yui.html Cycles:180 Average:19976.72 Median:4681.40 stddev:28162.79 (601.6%) stddev-sans-first:28204.53 11:28:50 INFO - PROCESS | 16886 | Values: 681.4 725.2 725.9 732.7 755.3 13476.1 13601.7 13718.7 13876.6 14973.2 13168.7 13379.3 13453.1 13540.7 13552.2 725.0 766.1 780.2 782.6 783.2 763.0 790.8 791.3 796.6 799.9 828.4 830.9 833.3 835.6 845.9 844.8 845.2 846.4 847.7 850.6 598.5 601.2 605.8 606.4 613.8 15367.8 15440.5 15440.5 15484.7 15688.9 13836.9 13999.7 14298.8 14587.5 14642.4 12148.9 12335.1 12441.9 12528.6 12574.1 3797.4 3806.3 3898.4 3934.1 3937.6 78845.0 79702.6 79980.6 80382.0 81343.2 10706.0 10725.1 11074.6 11178.0 11370.2 18323.5 20145.1 20289.0 20350.0 20414.0 8407.9 8540.1 8616.0 8700.3 8852.0 4606.5 4668.6 4694.2 4776.0 4874.1 3418.7 3471.7 3518.6 3531.3 3537.2 589.2 621.5 628.8 637.4 643.8 667.8 712.9 716.3 730.0 754.0 9444.5 9473.2 9547.9 9656.8 9672.3 694.5 702.5 714.2 725.3 743.7 4406.6 4442.8 4477.4 4533.8 4631.8 67966.7 106734.5 107440.0 108072.0 108734.0 92761.6 93972.5 94374.5 94407.5 94776.5 79530.2 80874.0 84022.0 85404.0 86374.6 8524.2 8550.4 8656.7 8756.8 9007.2 46181.8 51028.7 51042.0 51733.2 53209.7 66996.7 68150.7 68312.0 68965.3 72170.0 12296.3 12418.8 12865.3 13240.3 13371.9 14515.9 14579.1 15295.2 15445.8 15524.6 14149.6 14611.3 14757.6 15020.6 15037.6 13549.9 13595.9 13626.3 13764.9 14230.5 13901.2 13945.7 13963.9 14315.8 14343.4 10137.5 10194.3 10244.5 10446.3 10520.5 9775.7 11365.4 11488.1 11508.5 11656.0 11:28:50 INFO - PROCESS | 16886 | -------- Summary: end -------- 11:28:50 INFO - PROCESS | 16886 | 11:28:50 INFO - TEST-INFO | 16886: exit 0 11:28:51 INFO - TEST-OK | dromaeo_css | took 1111850ms 11:28:51 INFO - TEST-START | kraken 11:28:51 INFO - operating with platform_type : linux_ 11:28:51 INFO - Initialising browser for kraken test... 11:28:51 INFO - TEST-INFO | started process 17126 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJHlmsK/profile http://localhost:59118/getInfo.html) 11:28:55 INFO - PROCESS | 17126 | __metrics Screen width/height:1600/1200 11:28:55 INFO - PROCESS | 17126 | colorDepth:24 11:28:55 INFO - PROCESS | 17126 | Browser inner width/height: 1024/697 11:28:55 INFO - PROCESS | 17126 | __metrics 11:28:56 ERROR - PROCESS | 17126 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 11:28:57 INFO - TEST-INFO | 17126: exit 0 11:28:57 INFO - Browser initialized. 11:28:57 INFO - Running cycle 1/1 for kraken test... 11:28:57 INFO - TEST-INFO | started process 17245 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJHlmsK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:09 INFO - PROCESS | 17245 | RSS: Main: 184725504 11:29:09 INFO - PROCESS | 17245 | 11:29:12 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59118/tests/kraken/driver.html?testName=audio-beat-detection) 11:29:12 INFO - PROCESS | 17245 | RSS: Main: 190595072 11:29:12 INFO - PROCESS | 17245 | 11:29:15 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59118/tests/kraken/driver.html?testName=audio-dft) 11:29:16 INFO - PROCESS | 17245 | RSS: Main: 190087168 11:29:16 INFO - PROCESS | 17245 | 11:29:19 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59118/tests/kraken/driver.html?testName=audio-fft) 11:29:19 INFO - PROCESS | 17245 | RSS: Main: 185081856 11:29:19 INFO - PROCESS | 17245 | 11:29:22 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59118/tests/kraken/driver.html?testName=audio-oscillator) 11:29:22 INFO - PROCESS | 17245 | RSS: Main: 185683968 11:29:22 INFO - PROCESS | 17245 | 11:29:24 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59118/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:29:25 INFO - PROCESS | 17245 | RSS: Main: 186167296 11:29:25 INFO - PROCESS | 17245 | 11:29:28 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59118/tests/kraken/driver.html?testName=imaging-darkroom) 11:29:29 INFO - PROCESS | 17245 | RSS: Main: 186785792 11:29:29 INFO - PROCESS | 17245 | 11:29:32 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59118/tests/kraken/driver.html?testName=imaging-desaturate) 11:29:33 INFO - PROCESS | 17245 | RSS: Main: 186396672 11:29:33 INFO - PROCESS | 17245 | 11:29:36 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59118/tests/kraken/driver.html?testName=json-parse-financial) 11:29:36 INFO - PROCESS | 17245 | RSS: Main: 186126336 11:29:36 INFO - PROCESS | 17245 | 11:29:38 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59118/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:29:38 INFO - PROCESS | 17245 | RSS: Main: 186003456 11:29:38 INFO - PROCESS | 17245 | 11:29:40 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:29:41 INFO - PROCESS | 17245 | RSS: Main: 186638336 11:29:41 INFO - PROCESS | 17245 | 11:29:43 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:29:44 INFO - PROCESS | 17245 | RSS: Main: 187670528 11:29:44 INFO - PROCESS | 17245 | 11:29:46 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:29:47 INFO - PROCESS | 17245 | RSS: Main: 186757120 11:29:47 INFO - PROCESS | 17245 | 11:29:50 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:29:50 INFO - PROCESS | 17245 | RSS: Main: 186576896 11:29:50 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | Cycle 1(1): loaded http://localhost:59118/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59118/tests/kraken/driver.html?testName=ai-astar) 11:29:52 INFO - PROCESS | 17245 | RSS: Main: 187187200 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | __start_tp_report 11:29:52 INFO - PROCESS | 17245 | _x_x_mozilla_page_load 11:29:52 INFO - PROCESS | 17245 | _x_x_mozilla_page_load_details 11:29:52 INFO - PROCESS | 17245 | |i|pagename|runs| 11:29:52 INFO - PROCESS | 17245 | |0;ai-astar;112;108;112;111;114;113;111;113;111;111 11:29:52 INFO - PROCESS | 17245 | |1;audio-beat-detection;151;128;158;180;131;160;155;133;179;126 11:29:52 INFO - PROCESS | 17245 | |2;audio-dft;166;162;162;165;166;167;162;161;163;164 11:29:52 INFO - PROCESS | 17245 | |3;audio-fft;75;76;76;161;87;75;74;73;167;75 11:29:52 INFO - PROCESS | 17245 | |4;audio-oscillator;85;86;146;85;86;86;144;86;86;153 11:29:52 INFO - PROCESS | 17245 | |5;imaging-gaussian-blur;117;117;118;118;118;117;118;118;118;118 11:29:52 INFO - PROCESS | 17245 | |6;imaging-darkroom;147;146;147;147;150;148;148;148;147;147 11:29:52 INFO - PROCESS | 17245 | |7;imaging-desaturate;96;97;96;96;96;96;95;97;96;97 11:29:52 INFO - PROCESS | 17245 | |8;json-parse-financial;53;52;55;54;56;53;54;54;53;54 11:29:52 INFO - PROCESS | 17245 | |9;json-stringify-tinderbox;62;61;62;62;62;62;61;61;62;62 11:29:52 INFO - PROCESS | 17245 | |10;stanford-crypto-aes;81;79;78;80;82;87;81;82;109;81 11:29:52 INFO - PROCESS | 17245 | |11;stanford-crypto-ccm;136;123;142;129;127;124;133;125;126;124 11:29:52 INFO - PROCESS | 17245 | |12;stanford-crypto-pbkdf2;166;162;172;162;163;164;160;159;161;164 11:29:52 INFO - PROCESS | 17245 | |13;stanford-crypto-sha256-iterative;64;64;66;64;63;66;64;63;63;63 11:29:52 INFO - PROCESS | 17245 | __end_tp_report 11:29:52 INFO - PROCESS | 17245 | __start_cc_report 11:29:52 INFO - PROCESS | 17245 | _x_x_mozilla_cycle_collect,894 11:29:52 INFO - PROCESS | 17245 | __end_cc_report 11:29:52 INFO - PROCESS | 17245 | __startTimestamp1466533792629__endTimestamp 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | ------- Summary: start ------- 11:29:52 INFO - PROCESS | 17245 | Number of tests: 14 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:1.65 (1.5%) stddev-sans-first:1.74 11:29:52 INFO - PROCESS | 17245 | Values: 112.0 108.0 112.0 111.0 114.0 113.0 111.0 113.0 111.0 111.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#1] audio-beat-detection Cycles:10 Average:150.10 Median:156.50 stddev:20.11 (12.9%) stddev-sans-first:21.33 11:29:52 INFO - PROCESS | 17245 | Values: 151.0 128.0 158.0 180.0 131.0 160.0 155.0 133.0 179.0 126.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.50 stddev:2.10 (1.3%) stddev-sans-first:2.07 11:29:52 INFO - PROCESS | 17245 | Values: 166.0 162.0 162.0 165.0 166.0 167.0 162.0 161.0 163.0 164.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#3] audio-fft Cycles:10 Average:93.90 Median:75.00 stddev:37.18 (49.6%) stddev-sans-first:38.80 11:29:52 INFO - PROCESS | 17245 | Values: 75.0 76.0 76.0 161.0 87.0 75.0 74.0 73.0 167.0 75.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#4] audio-oscillator Cycles:10 Average:104.30 Median:86.00 stddev:30.01 (34.9%) stddev-sans-first:31.01 11:29:52 INFO - PROCESS | 17245 | Values: 85.0 86.0 146.0 85.0 86.0 86.0 144.0 86.0 86.0 153.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 11:29:52 INFO - PROCESS | 17245 | Values: 117.0 117.0 118.0 118.0 118.0 117.0 118.0 118.0 118.0 118.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 11:29:52 INFO - PROCESS | 17245 | Values: 147.0 146.0 147.0 147.0 150.0 148.0 148.0 148.0 147.0 147.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:29:52 INFO - PROCESS | 17245 | Values: 96.0 97.0 96.0 96.0 96.0 96.0 95.0 97.0 96.0 97.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.14 (2.1%) stddev-sans-first:1.17 11:29:52 INFO - PROCESS | 17245 | Values: 53.0 52.0 55.0 54.0 56.0 53.0 54.0 54.0 53.0 54.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 11:29:52 INFO - PROCESS | 17245 | Values: 62.0 61.0 62.0 62.0 62.0 62.0 61.0 61.0 62.0 62.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:81.00 stddev:9.10 (11.2%) stddev-sans-first:9.59 11:29:52 INFO - PROCESS | 17245 | Values: 81.0 79.0 78.0 80.0 82.0 87.0 81.0 82.0 109.0 81.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#11] stanford-crypto-ccm Cycles:10 Average:128.90 Median:128.00 stddev:6.23 (4.9%) stddev-sans-first:6.05 11:29:52 INFO - PROCESS | 17245 | Values: 136.0 123.0 142.0 129.0 127.0 124.0 133.0 125.0 126.0 124.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:163.50 stddev:3.68 (2.3%) stddev-sans-first:3.77 11:29:52 INFO - PROCESS | 17245 | Values: 166.0 162.0 172.0 162.0 163.0 164.0 160.0 159.0 161.0 164.0 11:29:52 INFO - PROCESS | 17245 | 11:29:52 INFO - PROCESS | 17245 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:1.15 (1.8%) stddev-sans-first:1.22 11:29:52 INFO - PROCESS | 17245 | Values: 64.0 64.0 66.0 64.0 63.0 66.0 64.0 63.0 63.0 63.0 11:29:52 INFO - PROCESS | 17245 | -------- Summary: end -------- 11:29:52 INFO - PROCESS | 17245 | 11:29:53 INFO - TEST-INFO | 17245: exit 0 11:29:53 INFO - TEST-OK | kraken | took 62613ms 11:29:53 INFO - SUITE-END | took 1174s 11:29:53 INFO - Completed test suite (00:19:34) 11:29:53 INFO - javascript benchmark 11:29:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7200.51156902164, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.9374012519734, 3139.073973177912, 3181.45915194417, 3185.299234168482, 3189.027346659268, 5358.651687640306, 5384.219288203209, 5404.594655400846, 5456.945430545693, 5505.449455054492, 5333.946660533394, 5349.358077030752, 5373.6775793452325, 5376.892462150755, 5405.027095122886, 4860.708357498544, 4885.120678277908, 4972.627052971024, 5018.874528136789, 5068.518490743392, 691.5998024607445, 4919.655624106314, 4960.156773348522, 4975.875603109933, 5002.149634562115, 4902.877428064291, 4904.901901961976, 4905.117078925774, 4921.901561968759, 4990.6257030722845, 4794.9520504794855, 4938.185199442088, 4948.1093403187415, 4970.0, 5020.723860187688, 4830.903381932341, 4927.359443272351, 4937.35814344133, 4978.751062446863, 4981.103401387749, 5439.265699130646, 5441.646292990943, 5445.101558242885, 5473.644213126174, 5475.972620136874, 5703.0, 5849.064149736065, 5853.824385268449, 5861.150133230658, 5921.111833225008, 5770.394108618619, 5816.331121920991, 5821.621594596338, 5858.150568167592, 5862.267216597926, 5437.6465529740835, 5439.184122381635, 5449.591280653967, 5460.31746031746, 5465.316835395575, 5651.350094739076, 5700.087985922232, 5903.3801450847905, 5911.881762364728, 5918.46733793952, 5591.245181900392, 5594.468525490072, 5632.014397480424, 5647.548196144298, 5722.427757224327, 5675.858103547444, 5720.456556627197, 5722.971385143131, 5731.996900542388, 5753.4821866031425, 5612.298462692163, 5640.774369025193, 5685.943140568624, 5708.229389032511, 5733.082706766897, 5793.507551858139, 5836.737346819404, 5841.094630332221, 5877.52979761618, 5880.353161152269, 4400.273954797487, 4460.7323560586465, 4463.464384273908, 4475.373447717322, 4502.482214545369, 3923.195744872294, 3926.2932672118723, 3942.3692209246956, 3995.6603688686787, 4033.1328764315226, 5503.3395992481155, 5540.335159780852, 5552.69460179694, 5566.860828479239, 5630.85922851932, 5837.066069428956, 5843.532517398683, 5851.034579294454, 5855.443732845373, 5857.326407463111, 5393.892122157535, 5398.190271459234, 5435.537979271727, 5543.113101903676, 5556.055470570012, 4897.216445368796, 4917.7786999585105, 4922.483139270396, 4922.9753851231235, 4934.8026078957155, 5853.560982926298, 5906.793262235801, 5911.556633252523, 5927.347991720907, 5929.495992840571, 5724.8568785779025, 5725.77096916127, 5741.167530708108, 5801.535877129904, 5806.709664516842, 5915.378885217162, 5921.940780592311, 5937.851553711192, 5938.910916336345, 5948.8810223796145, 5583.525400341016, 5636.971815140898, 5637.718114094361, 5640.576956728344, 5658.122990936411, 5641.830745077655, 5723.227364305765, 5765.192872997867, 5801.245838041028, 5808.477237048686, 5612.466815652507, 5639.351474580476, 5683.943160568341, 5704.172894930129, 5708.0, 5673.517750991212, 5690.231818704422, 5691.317041954826, 5692.7722891083895, 5711.771529138788, 5455.809046683347, 5502.724863756716, 5506.889862202654, 5537.0, 5554.527865131509, 5644.887102258014, 5650.463205995424, 5658.236138121301, 5663.461971112738, 5667.4615911488345, 5452.7546260417585, 5508.394076651645, 5542.0855558833955, 5551.750171242385, 5565.777368905198], "value": 5299.740677664803, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.725396880024, 588.3028611095851, 601.2844714789402, 602.4036204157879, 605.3492495567266, 41349.379759303636, 42697.78651106744, 42816.357754633704, 42850.142997140036, 46947.0, 43918.780406098005, 44594.0, 44595.0, 44749.776251118696, 44976.77511612437, 8909.109089091104, 9095.727128186132, 9110.0, 9112.134347237003, 9134.634614615408, 8945.507997060191, 8978.461292322481, 8997.145271199226, 9009.684661036865, 9010.819783604324, 8987.910120898805, 9030.638774449015, 9052.954735226314, 9069.319801014917, 9071.637134514645, 8876.600552975131, 8934.329925255599, 8941.329400294971, 8951.955240223757, 8958.820823583492, 8549.273311768507, 8554.272886804567, 8616.095309992486, 8625.56872156396, 8626.913730862736, 41263.38104928428, 41667.37498937518, 41746.58253417457, 41811.0, 41852.790736046125, 3540.5574303212097, 4080.9389558714947, 4083.1221287423346, 4143.606357396043, 4295.763732994684, 31790.682093179003, 32373.38133093336, 32421.67578324234, 32426.18934526632, 32755.50866737001, 4133.111381053058, 4231.946068310163, 4254.42565253693, 4274.123804620045, 4283.378910058024, 337723.0, 339377.0, 340805.0, 341139.2943035294, 341165.0, 54281.72859135679, 54685.45314546803, 54686.0, 54709.72645136749, 55578.0, 32983.50524742131, 48080.5191948083, 48593.27110093351, 48688.26967595489, 48906.5109348902, 15144.848551514564, 16064.598385040468, 16091.275892584628, 16114.0, 16119.67760644804, 11075.0, 11091.611793587186, 11104.888951110386, 11106.111511079094, 11128.38793866345, 18411.53971150732, 18597.7210341845, 18634.347797827282, 18717.532061698566, 18718.53203669919, 6857.245702972669, 7134.500584959107, 7242.565446073252, 7259.346658800628, 7314.524555903849, 923.6859467780987, 1024.329064462778, 1028.4138041316528, 1100.4772732951944, 1102.415719668577, 32123.839380802947, 32345.838270808494, 32912.67087329096, 32956.0, 33354.166145846066, 1082.9350238985687, 1109.5617231193633, 1171.5196769324536, 1211.2127117373602, 1216.7688139253514, 16499.340026398808, 16739.916300418663, 16790.74813877793, 16938.661226775395, 17342.219600118024, 586196.0, 586684.0, 586765.0, 587941.0, 590901.0, 56831.715841420526, 57460.71269643625, 57472.42527574754, 57628.42371576231, 57774.133387999216, 1253.6677780388204, 1314.7764879970428, 1330.6340756292097, 1333.9333033348294, 1338.7255612599395, 30813.22966925845, 31683.683163168997, 32130.83934580312, 32243.193920152185, 32282.677173227967, 49741.0, 49741.751291243316, 49847.252291216384, 49979.50020499749, 50156.247656285916, 422692.0, 428163.0, 430422.0, 431924.0, 433538.0, 27450.0, 28010.2997425066, 28025.299367515974, 28044.0, 28059.0, 46715.766421167675, 48337.51662483471, 49152.75423622859, 52238.21642675439, 52960.735196323774, 40118.799406002785, 40799.38800918048, 41330.38004429996, 42238.57761422346, 42714.0, 40492.3926141114, 40534.59465405308, 40565.59434405618, 41183.7940810294, 41848.163036738486, 40037.0, 41461.79269103635, 42536.78731606322, 42707.0, 42707.35938960856, 34925.30149396947, 35932.820335898155, 36944.81527592345, 37037.25925481529, 37085.443718343704, 27777.44445111127, 28630.713692862806, 28734.71265287404, 28961.56557651679, 29205.561916570845], "value": 19936.370467279066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.1031352559179, 366.94312381580846, 368.61847987333107, 373.9556694350598, 378.77462909568794, 17815.10924453777, 18461.446156615308, 18820.435386938396, 19164.233430662793, 19267.421977340666, 17926.82073179268, 17953.28186872527, 18038.909805450956, 18975.62048759024, 18976.146073426695, 5584.4136365681525, 5734.483896449318, 5760.53915686744, 5789.363170051312, 5796.536277097843, 5554.222408862763, 5594.916076258839, 5657.2928383952, 5675.5459563234845, 5696.316442026942, 5630.859228519299, 5635.943640563603, 5645.8588535286535, 5653.0, 5675.6878371689545, 5617.522510586605, 5636.859078523029, 5649.406812284713, 5665.49010589049, 5674.3190817101795, 4981.72600506972, 4984.252362145708, 5008.524190201925, 5017.147084995523, 5028.094942910275, 21424.25015124479, 22162.11351545936, 22245.11019559216, 22322.0, 22509.212177573707, 693.2028167607242, 693.9410150137245, 947.6351604632197, 1997.6304383688919, 2327.1156960354956, 13406.262655553941, 13985.510507132201, 14122.505712300122, 14232.715345693132, 14501.637459063502, 1914.712793081035, 1970.4975231315923, 1990.0004950250075, 1992.721019057333, 2019.7677267114323, 67624.3237567623, 75915.62042189809, 82859.0, 84082.57958710167, 84089.99999999939, 9978.401295922267, 10581.62964296246, 10605.204609654307, 10912.127029837595, 10923.289986150874, 14820.925895370454, 14876.851231487763, 14890.0, 14902.70194596102, 14932.104073755392, 7454.440916931252, 7509.51188172778, 7552.962235188789, 7630.8092297693, 7638.312551870358, 7124.715011399486, 7225.927740722525, 7238.0952380952385, 7349.926500735031, 7715.537067775951, 4450.821967121279, 4513.774311284423, 4575.862724118282, 4627.8611641650805, 4634.420697412824, 1830.1855674225108, 1833.7432759413693, 1858.1823997441352, 1859.869809113376, 1860.2280053777802, 850.2475309351269, 976.1800087926176, 1011.2819897872446, 1091.5033659684825, 1107.1198397274186, 17017.404390846263, 18456.0, 18590.721139182922, 19211.0, 19212.327568535035, 948.0472125513814, 950.9239260859115, 968.6464440479174, 973.1290495006929, 978.6427953796813, 10165.28842980999, 10240.48797560119, 10400.375977441377, 10641.5211315491, 10753.085987691135, 376096.0, 382543.0, 385327.0, 496814.0, 544168.0, 93066.0, 94315.52842235746, 95879.0, 97243.51378243063, 98265.0, 992.8262554053011, 1019.9847002294972, 1038.2991480750463, 1044.7805960748328, 1053.9789204215872, 16497.257623406735, 17780.822191778436, 17830.197641106442, 17911.10444477797, 18075.638487230444, 40336.3949540763, 41752.0, 42079.36880946727, 42219.3667095, 43333.34999975066, 59275.40724592699, 59304.40695592989, 60453.0, 61402.38597613967, 61597.38402616096, 8378.120297368689, 8428.283595894172, 8534.103919088406, 8567.27178189861, 8651.394402391772, 11887.46506407231, 13172.934135329262, 13305.667358315732, 13477.32613369308, 13546.0, 11789.941050294694, 11897.940510297392, 11920.463579139134, 11927.82108268394, 12255.877441225473, 11753.236039657395, 11825.0, 11914.880851191723, 11961.282323060645, 12074.818877716665, 11328.660140195807, 12131.0, 12215.816762748744, 12217.69455763613, 12223.44994475233, 8216.09622941488, 8380.329573634217, 8405.915940840514, 8442.788930276793, 8477.576121193792, 11426.2001659883, 11453.713657158638, 11466.541338346706, 11754.23597466162, 12077.51689932393], "value": 8983.597102273447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.1442848219863, 1413.4558195094885, 1531.4869518711232, 1690.4928521443564, 1697.3550050980625, 7736.806579835507, 8244.876326855101, 8271.421000529957, 8750.08124146965, 8761.868571971418, 8287.502749835, 8557.657693692261, 8577.14228577142, 8631.266342360892, 8641.092685268051, 2260.4235919840416, 2293.81649468043, 2317.97177469714, 2318.026428899866, 2318.3508617587104, 2242.9663555046686, 2250.8086812620945, 2291.461506545969, 2295.1966811615966, 2298.8275879309035, 2243.7756224377595, 2267.1724820440504, 2281.851679640818, 2283.3720726800098, 2285.462916214688, 2169.6962425260476, 2171.7285339332584, 2176.096919778298, 2178.400939741568, 2191.9561608767895, 2148.98925505373, 2187.7155969723995, 2194.4294483434264, 2195.5169862630196, 2198.7801219878043, 8745.300375969908, 8790.164934331293, 8791.956040219759, 8819.162179592928, 8822.867656985087, 4939.753012349404, 5018.498150184989, 5021.447640759514, 5064.772085256135, 5080.542751152414, 3135.7961732487543, 3216.6300875399393, 3344.4314466540504, 3367.478040903664, 3379.4423920053164, 1139.960101396447, 1144.8855114488567, 1155.9711007224803, 1156.6009726644295, 1170.2101081769772, 12160.817587736192, 12198.634040978784, 12206.145569810116, 12224.755504889941, 12232.510699572007, 4405.185040767468, 4441.822327106879, 4513.029698614748, 4518.277075667877, 4528.298113792367, 10207.9489602553, 10258.897411025795, 10266.384016959006, 10286.639967601099, 10353.327033742782, 4991.376077990251, 5000.549950504399, 5039.4708555601865, 5079.425378132791, 5087.516685914832, 4162.334026555736, 4514.210013247734, 4611.123886461562, 4684.882877928011, 4691.202495575761, 3741.4761933329355, 3773.5471743390967, 3783.848646054182, 3834.1564855731694, 3984.561698213194, 1198.5924785572947, 1218.993905030487, 1232.3037483821613, 1239.6652903715947, 1243.830799048891, 823.4400607586779, 892.2549671024638, 915.199200699388, 926.995365023184, 935.4434111703525, 5835.270591176103, 5876.382979787147, 5958.6424814511265, 6120.265568131853, 6287.339829317947, 944.607987685103, 949.4635530925143, 954.4511905654276, 956.990430095704, 969.8981606931312, 4051.554329023805, 4202.180574787948, 4281.828726850891, 4314.460692413449, 4317.503487098961, 10630.52162652667, 10891.727706807393, 10963.780724385468, 10987.285826421257, 10998.450077496253, 10528.0, 10784.299020563638, 10843.240973131962, 10886.129109671207, 10949.671509854717, 7806.648700808477, 7833.785763206712, 7896.881546776803, 7957.6021198939825, 8025.678972841138, 5891.293044834647, 6104.084387341934, 6133.877322453437, 6240.656763878031, 6288.245410550763, 9671.9032809671, 9841.507924603884, 9926.751831203988, 10011.599536018477, 10144.442055687008, 10198.643047493302, 10271.126954208976, 10307.484625768531, 10325.0, 10328.225383096147, 7822.530648161129, 7890.9605451972375, 8005.279524842792, 8067.515949042841, 8076.030876294883, 8130.75607731769, 8136.145704701158, 8198.754037378889, 8206.179382061984, 8240.299574535991, 7468.5145465544565, 7692.115406728298, 7761.301482866569, 7771.339436147765, 7782.766517004499, 7192.352688258082, 7214.350708436266, 7282.1625513064555, 7334.633268336668, 7475.140358858801, 6912.861742765217, 6921.480888933451, 6974.965125174342, 6993.930060699327, 7036.894446583203, 5081.11080560893, 5090.778213228725, 5227.398849132246, 5283.233931079895, 5340.225667278145, 4927.556519913225, 5164.715940623302, 5186.58507319406, 5266.210068489757, 5319.893602128013], "value": 4565.282660597681, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.59897341668494, 296.8619591889774, 300.17751361270126, 311.1862479615805, 313.57353998561956, 5634.40838711935, 5653.434656534345, 5687.772489100442, 5689.203511508382, 5695.430456954303, 5553.750081246343, 5579.107342825138, 5584.1902924075985, 5589.7205139743055, 5598.524125449331, 4044.5348784889748, 4140.171965606875, 4253.595908388714, 4258.212230737324, 4281.828726850922, 4221.936670949938, 4240.830366785325, 4255.765932873691, 4263.59495847894, 4272.957270427302, 4215.915681686364, 4229.830806767741, 4235.68232382571, 4239.745615263094, 4246.851360202393, 3995.2209319182916, 4179.331306990897, 4193.553224137698, 4199.328107502785, 4230.492340919079, 3616.27674465108, 3634.963650363489, 3658.32321020612, 3664.358737220976, 3673.338799016176, 5386.892262154735, 5450.618456708032, 5452.182172674091, 5480.972595137038, 5485.75314110862, 4567.9086418271445, 4825.348577941968, 4825.4933232010835, 4866.635002374836, 4872.756362181876, 5838.737256823453, 5844.707764611752, 5859.121131830217, 5878.559108066912, 5881.323647780518, 4006.777932730516, 4030.6976976726864, 4033.882211838779, 4035.233305671913, 4045.8583949561917, 6006.8498287542725, 6040.124181993587, 6087.360827113133, 6099.085137229406, 6111.480524155452, 3969.0871099647243, 3974.4634474345594, 3984.621460961204, 4020.6984476164403, 4031.294523458383, 4004.539477960072, 4015.477987861559, 4043.8786836394956, 4051.817668204879, 4053.108316170438, 4597.954020459752, 4701.717896926195, 4727.581725482411, 4758.643101767381, 4770.880727981828, 5820.738066787005, 5828.446297601721, 5884.558658100659, 5884.705764711747, 5887.264091988502, 1415.3277193333086, 1434.2040167706814, 1434.2326855132453, 1439.9064060836013, 1447.486142419426, 876.6975393489236, 896.5786080542135, 898.6899519665704, 920.6869664314165, 960.4573416019927, 5508.862278442991, 5542.695151766691, 5578.888422231533, 5587.636803607753, 5623.606347555632, 5837.795677151279, 5863.5309175265875, 5868.413158684183, 5869.383714709979, 5888.793892213837, 5340.359156901197, 5536.612437129443, 5561.777528898799, 5593.412691667398, 5660.688662123623, 3161.604799400075, 3178.1260153457984, 3182.840857957093, 3193.680631936788, 3198.2484116232667, 5826.388229235954, 6029.427204415573, 6107.267127944675, 6130.743197644472, 6169.629822210682, 5995.850103747354, 6012.609180403348, 6026.0, 6026.638401695912, 6028.367021462771, 6001.319630503466, 6008.699565021731, 6060.424259695322, 6100.712232912658, 6136.465883529207, 5756.280464941882, 5934.258217722785, 5948.821535353946, 5950.880982380415, 5968.970155149196, 5863.0, 5883.705814709162, 5903.911441328298, 5919.822405327847, 5921.555883308855, 5997.320750189954, 6011.789587364421, 6093.756249750138, 6093.969530152321, 6111.449969502766, 4110.712250142461, 4131.772752498641, 4133.379993001074, 4141.91716165681, 4152.979235103926, 5914.290285165807, 5968.432998865101, 5993.490553303018, 6005.39946005396, 6020.759169633166, 5422.9310242099455, 5691.373948865705, 5701.6316084140335, 5759.9136012959, 5785.18588818235, 5624.634398764068, 5662.745176467151, 5720.799772007959, 5786.755847492809, 5807.486470685343, 5434.945650543444, 5533.253010843646, 5591.133374326986, 5643.830685079454, 5655.575831812713, 5780.468175933297, 5793.710314484343, 5798.477662216949, 5810.82567522975, 5810.912836307374, 5561.221429000024, 5651.237082993907, 5655.7840064386355, 5668.234788303526, 5680.7443665036], "value": 4346.821889392181, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.3697329969776, 725.2058995400035, 725.8874874394471, 732.714241445836, 755.2636179724769, 13476.124051936617, 13601.727965440685, 13718.657033574165, 13876.58370248893, 14973.176475293854, 13168.670783230424, 13379.331033448338, 13453.125546839448, 13540.661483462894, 13552.18686878788, 725.0429433148247, 766.1380946435246, 780.2314720000822, 782.636074225485, 783.2363445640511, 762.9585615634675, 790.8378782349633, 791.2601717394255, 796.6056801883076, 799.8800179973022, 828.4325237212498, 830.9293710034655, 833.2584000239791, 835.5947365527715, 845.9323254139684, 844.7507985144397, 845.2097289034741, 846.3567688556741, 847.7117779954814, 850.5619605902965, 598.4703537369415, 601.1553766957431, 605.7758629307141, 606.4451027310006, 613.8434699151733, 15367.846321536865, 15440.45958391451, 15440.536783896388, 15484.690306193927, 15688.92155539215, 13836.930815345959, 13999.720005599933, 14298.785518217235, 14587.489437869624, 14642.41430342785, 12148.878511214863, 12335.136540442083, 12441.875581244161, 12528.561500347536, 12574.11981161319, 3797.411401232813, 3806.26611144158, 3898.3567711327596, 3934.075492259317, 3937.625925537069, 78845.0, 79702.60148699277, 79980.60009699973, 80382.0, 81343.18656813416, 10705.982931621484, 10725.14198864089, 11074.612388566362, 11177.99398054179, 11370.204085714086, 18323.54191145232, 20145.09347079385, 20289.0, 20350.0, 20414.0, 8407.873881891777, 8540.10328915455, 8616.0, 8700.34747393948, 8851.955740221387, 4606.539346065434, 4668.603168730696, 4694.201985662446, 4775.95224047762, 4874.147024270738, 3418.7094097001536, 3471.7222622190166, 3518.595361533405, 3531.29374125176, 3537.168765340143, 589.163387989048, 621.4841681404423, 628.7767842415968, 637.4454224824433, 643.8261669349249, 667.8063361625075, 712.8629835412509, 716.3266529462289, 730.0071902212986, 753.9698412063456, 9444.48055356962, 9473.242140628734, 9547.90452095484, 9656.75858103553, 9672.322937394314, 694.465261748461, 702.4836744992426, 714.242902523327, 725.2674798453495, 743.7211045857804, 4406.5593440655675, 4442.844500442534, 4477.440319960005, 4533.77331133442, 4631.814727410933, 67966.66016669886, 106734.46632766786, 107440.0, 108072.0, 108734.0, 92761.60857587142, 93972.53013734888, 94374.52812735892, 94407.52796235975, 94776.52611737035, 79530.20469795344, 80874.0, 84022.0, 85404.0, 86374.56812715896, 8524.201540890399, 8550.35872309567, 8656.697015604424, 8756.781080472785, 9007.189352958265, 46181.76909115433, 51028.74485627548, 51042.0, 51733.22400164076, 53209.733951329996, 66996.66501667461, 68150.65924670345, 68312.0, 68965.31034689589, 72170.0, 12296.262224266573, 12418.820212079838, 12865.2924089176, 13240.27178505153, 13371.866281337063, 14515.92742036283, 14579.125252484884, 15295.184203709994, 15445.84554154444, 15524.611884702972, 14149.575512734635, 14611.269436527919, 14757.557273281818, 15020.624484387978, 15037.624059398602, 13549.86450135486, 13595.932020339835, 13626.250556219504, 13764.862351376358, 14230.501932432315, 13901.165930043826, 13945.721085578434, 13963.930180349033, 14315.785263220852, 14343.354549045109, 10137.493125343852, 10194.286399951932, 10244.487775611338, 10446.268761186644, 10520.473976301308, 9775.706728798148, 11365.374904380007, 11488.094048023711, 11508.49031771038, 11656.0], "value": 7398.534445687524, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 112.0, 111.0, 114.0, 113.0, 111.0, 113.0, 111.0, 111.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 128.0, 158.0, 180.0, 131.0, 160.0, 155.0, 133.0, 179.0, 126.0], "value": 150.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 162.0, 165.0, 166.0, 167.0, 162.0, 161.0, 163.0, 164.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 161.0, 87.0, 75.0, 74.0, 73.0, 167.0, 75.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 146.0, 85.0, 86.0, 86.0, 144.0, 86.0, 86.0, 153.0], "value": 104.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 150.0, 148.0, 148.0, 148.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 95.0, 97.0, 96.0, 97.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 55.0, 54.0, 56.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 61.0, 62.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 78.0, 80.0, 82.0, 87.0, 81.0, 82.0, 109.0, 81.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 123.0, 142.0, 129.0, 127.0, 124.0, 133.0, 125.0, 126.0, 124.0], "value": 128.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 172.0, 162.0, 163.0, 164.0, 160.0, 159.0, 161.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 64.0, 63.0, 66.0, 64.0, 63.0, 63.0, 63.0], "value": 64.0, "unit": "score"}]}]} 11:29:53 INFO - Return code: 0 11:29:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:29:53 ERROR - # TBPL SUCCESS # 11:29:53 INFO - Running post-action listener: _resource_record_post_action 11:29:53 INFO - ##### 11:29:53 INFO - ##### Finished run-tests step (success) 11:29:53 INFO - ##### 11:29:53 INFO - Running post-run listener: _resource_record_post_run 11:29:53 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 552263680; Read time: 8; Write time: 526044 11:29:53 INFO - TinderboxPrint: CPU usage
13.4% 11:29:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 11:29:53 INFO - TinderboxPrint: I/O write bytes / time
552,263,680 / 526,044 11:29:53 INFO - TinderboxPrint: CPU idle
8,234.8 (86.5%) 11:29:53 INFO - TinderboxPrint: CPU user
1,221.6 (12.8%) 11:29:53 INFO - TinderboxPrint: Swap in / out
0 / 0 11:29:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 200290304; Read time: 0; Write time: 253792 11:29:54 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288944128; Read time: 8; Write time: 244604 11:29:54 INFO - Running post-run listener: _upload_blobber_files 11:29:54 INFO - Blob upload gear active. 11:29:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:29:54 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 11:29:54 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'] 11:29:54 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 11:29:54 INFO - (blobuploader) - INFO - Open directory for files ... 11:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:58 INFO - (blobuploader) - INFO - Done attempting. 11:29:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:29:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:29:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:59 INFO - (blobuploader) - INFO - Done attempting. 11:29:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:29:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:30:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:00 INFO - (blobuploader) - INFO - Done attempting. 11:30:00 INFO - (blobuploader) - INFO - Iteration through files over. 11:30:00 INFO - Return code: 0 11:30:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:30:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:30:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7416d3c9a5faf51d759bed500228f7b1252e4b0a3078e42bb5120b8ee2a25ac4a823ba38456fc82268c8340184330fc2facbdeed540fe9d6807c4de5c7f07773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed5c1ee79200f332ccddcdc32b4155ac6a7c7fe5450754a79c0224144bd9ff6fd9a38ea48c0e765f2700caf113c29ea5a55ba823771d62f25daa41a0c33ef48f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e76278495da4c98ae8ba200b6b52a0c318f333cd4f588f809a0388546d5953cd5f39f2c804c85d8087dc345c5b98d8361b72f506308b89a64626ccb5b0847a"} 11:30:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:30:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:30:00 INFO - Contents: 11:30:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7416d3c9a5faf51d759bed500228f7b1252e4b0a3078e42bb5120b8ee2a25ac4a823ba38456fc82268c8340184330fc2facbdeed540fe9d6807c4de5c7f07773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed5c1ee79200f332ccddcdc32b4155ac6a7c7fe5450754a79c0224144bd9ff6fd9a38ea48c0e765f2700caf113c29ea5a55ba823771d62f25daa41a0c33ef48f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e76278495da4c98ae8ba200b6b52a0c318f333cd4f588f809a0388546d5953cd5f39f2c804c85d8087dc345c5b98d8361b72f506308b89a64626ccb5b0847a"} 11:30:00 INFO - Running post-run listener: copy_logs_to_upload_dir 11:30:00 INFO - Copying logs to upload dir... 11:30:00 INFO - mkdir: /builds/slave/test/build/upload/logs 11:30:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.111206 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-21 11:29:59.952374) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 11:29:59.955676) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7416d3c9a5faf51d759bed500228f7b1252e4b0a3078e42bb5120b8ee2a25ac4a823ba38456fc82268c8340184330fc2facbdeed540fe9d6807c4de5c7f07773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed5c1ee79200f332ccddcdc32b4155ac6a7c7fe5450754a79c0224144bd9ff6fd9a38ea48c0e765f2700caf113c29ea5a55ba823771d62f25daa41a0c33ef48f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e76278495da4c98ae8ba200b6b52a0c318f333cd4f588f809a0388546d5953cd5f39f2c804c85d8087dc345c5b98d8361b72f506308b89a64626ccb5b0847a"} build_url:https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011418 build_url: 'https://queue.taskcluster.net/v1/task/aMV7shOCS1eptfHjzLhAxA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7416d3c9a5faf51d759bed500228f7b1252e4b0a3078e42bb5120b8ee2a25ac4a823ba38456fc82268c8340184330fc2facbdeed540fe9d6807c4de5c7f07773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed5c1ee79200f332ccddcdc32b4155ac6a7c7fe5450754a79c0224144bd9ff6fd9a38ea48c0e765f2700caf113c29ea5a55ba823771d62f25daa41a0c33ef48f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88e76278495da4c98ae8ba200b6b52a0c318f333cd4f588f809a0388546d5953cd5f39f2c804c85d8087dc345c5b98d8361b72f506308b89a64626ccb5b0847a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 11:29:59.980953) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:29:59.981269) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1466532513.918171-996430393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:30:00.065913) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 11:30:00.066205) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 11:30:00.066488) ========= ========= Total master_lag: 0.36 =========