builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1466166263.42 results: success (0) buildid: 20160617023006 builduid: 3c64782603b04a8c913c5737adc00ea4 revision: 048bc3681be741fa5677744a0a30cff6a7358557 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.419019) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.419537) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.419842) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.438437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.438762) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-17 05:24:23-- 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% 42.7M=0s 2016-06-17 05:24:23 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087677 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.539916) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.540207) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018832 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.589318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:23.589691) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 048bc3681be741fa5677744a0a30cff6a7358557 --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 048bc3681be741fa5677744a0a30cff6a7358557 --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=9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-17 05:24:23,893 truncating revision to first 12 chars 2016-06-17 05:24:23,893 Setting DEBUG logging. 2016-06-17 05:24:23,893 attempt 1/10 2016-06-17 05:24:23,893 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/048bc3681be7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-17 05:24:24,281 unpacking tar archive at: fx-team-048bc3681be7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.726346 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:24.329668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:24.330083) ========= script_repo_revision: 048bc3681be741fa5677744a0a30cff6a7358557 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:24.330623) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:24.330978) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 05:24:24.340093) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-06-17 05:24:24.340521) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:24:24 INFO - MultiFileLogger online at 20160617 05:24:24 in /builds/slave/test 05:24:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:24:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:24:24 INFO - {'append_to_log': False, 05:24:24 INFO - 'base_work_dir': '/builds/slave/test', 05:24:24 INFO - 'blob_upload_branch': 'Fx-Team', 05:24:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:24:24 INFO - 'branch': 'Fx-Team', 05:24:24 INFO - 'buildbot_json_path': 'buildprops.json', 05:24:24 INFO - 'config_files': ('talos/linux_config.py',), 05:24:24 INFO - 'default_actions': ('clobber', 05:24:24 INFO - 'read-buildbot-config', 05:24:24 INFO - 'download-and-extract', 05:24:24 INFO - 'populate-webroot', 05:24:24 INFO - 'create-virtualenv', 05:24:24 INFO - 'install', 05:24:24 INFO - 'run-tests'), 05:24:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:24:24 INFO - 'download_minidump_stackwalk': True, 05:24:24 INFO - 'download_symbols': 'ondemand', 05:24:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:24:24 INFO - 'tooltool.py': '/tools/tooltool.py', 05:24:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:24:24 INFO - '/tools/misc-python/virtualenv.py')}, 05:24:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:24:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:24:24 INFO - 'installer_path': 'installer.exe', 05:24:24 INFO - 'log_level': 'info', 05:24:24 INFO - 'log_name': 'talos', 05:24:24 INFO - 'log_to_console': True, 05:24:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:24:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:24:24 INFO - 'opt_config_files': (), 05:24:24 INFO - 'pip_index': False, 05:24:24 INFO - 'sps_profile': False, 05:24:24 INFO - 'sps_profile_interval': 0, 05:24:24 INFO - 'suite': 'dromaeojs', 05:24:24 INFO - 'system_bits': '32', 05:24:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:24:24 INFO - 'title': 'talos-linux64-ix-013', 05:24:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:24:24 INFO - 'use_talos_json': True, 05:24:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:24:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:24:24 INFO - 'work_dir': 'build'} 05:24:24 INFO - ##### 05:24:24 INFO - ##### Running clobber step. 05:24:24 INFO - ##### 05:24:24 INFO - Running pre-action listener: _resource_record_pre_action 05:24:24 INFO - Running main action method: clobber 05:24:24 INFO - rmtree: /builds/slave/test/build 05:24:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:24:25 INFO - Running post-action listener: _resource_record_post_action 05:24:25 INFO - ##### 05:24:25 INFO - ##### Finished clobber step (success) 05:24:25 INFO - ##### 05:24:25 INFO - ##### 05:24:25 INFO - ##### Running read-buildbot-config step. 05:24:25 INFO - ##### 05:24:25 INFO - Running pre-action listener: _resource_record_pre_action 05:24:25 INFO - Running main action method: read_buildbot_config 05:24:25 INFO - Using buildbot properties: 05:24:25 INFO - { 05:24:25 INFO - "project": "", 05:24:25 INFO - "product": "firefox", 05:24:25 INFO - "script_repo_revision": "production", 05:24:25 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:24:25 INFO - "repository": "", 05:24:25 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:24:25 INFO - "buildid": "20160617023006", 05:24:25 INFO - "pgo_build": "True", 05:24:25 INFO - "basedir": "/builds/slave/test", 05:24:25 INFO - "buildnumber": 193, 05:24:25 INFO - "slavename": "talos-linux64-ix-013", 05:24:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:24:25 INFO - "platform": "ubuntu64_hw", 05:24:25 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:24:25 INFO - "branch": "fx-team", 05:24:25 INFO - "repo_path": "integration/fx-team", 05:24:25 INFO - "revision": "048bc3681be741fa5677744a0a30cff6a7358557", 05:24:25 INFO - "stage_platform": "linux64-pgo", 05:24:25 INFO - "builduid": "3c64782603b04a8c913c5737adc00ea4", 05:24:25 INFO - "slavebuilddir": "test" 05:24:25 INFO - } 05:24:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:24:25 INFO - Running post-action listener: _resource_record_post_action 05:24:25 INFO - ##### 05:24:25 INFO - ##### Finished read-buildbot-config step (success) 05:24:25 INFO - ##### 05:24:25 INFO - ##### 05:24:25 INFO - ##### Running download-and-extract step. 05:24:25 INFO - ##### 05:24:25 INFO - Running pre-action listener: _resource_record_pre_action 05:24:25 INFO - Running main action method: download_and_extract 05:24:25 INFO - mkdir: /builds/slave/test/build/tests 05:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:25 INFO - https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:24:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:24:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:24:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:25 INFO - Downloaded 1361 bytes. 05:24:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:24:25 INFO - Using the following test package requirements: 05:24:25 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:24:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:24:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:24:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'jsshell-linux-x86_64.zip'], 05:24:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:24:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:24:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:24:25 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:24:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:24:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:24:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:24:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:25 INFO - https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:24:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:24:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:24:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:27 INFO - Downloaded 24220995 bytes. 05:24:27 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'] 05:24:27 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 05:24:28 INFO - Return code: 0 05:24:28 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 05:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:28 INFO - https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:24:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:24:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:24:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:35 INFO - Downloaded 24220995 bytes. 05:24:35 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'] 05:24:35 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 05:24:36 INFO - Return code: 0 05:24:36 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')]} 05:24:36 INFO - https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:24:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:24:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:24:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:37 INFO - Downloaded 14405384 bytes. 05:24:37 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'] 05:24:37 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 05:24:37 INFO - Return code: 0 05:24:37 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')]} 05:24:37 INFO - https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:24:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:24:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:24:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FLzwKnF1RhimQR5WI8FYNg/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 05:24:42 INFO - Downloaded 59402061 bytes. 05:24:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:24:42 INFO - mkdir: /builds/slave/test/properties 05:24:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:24:42 INFO - Writing to file /builds/slave/test/properties/build_url 05:24:42 INFO - Contents: 05:24:42 INFO - build_url:https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:24:43 INFO - Running post-action listener: _resource_record_post_action 05:24:43 INFO - Running post-action listener: set_extra_try_arguments 05:24:43 INFO - ##### 05:24:43 INFO - ##### Finished download-and-extract step (success) 05:24:43 INFO - ##### 05:24:43 INFO - ##### 05:24:43 INFO - ##### Running populate-webroot step. 05:24:43 INFO - ##### 05:24:43 INFO - Running pre-action listener: _resource_record_pre_action 05:24:43 INFO - Running main action method: populate_webroot 05:24:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:24:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:24:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:24:43 INFO - '--tppagecycles', 05:24:43 INFO - '7'], 05:24:43 INFO - 'tests': ['tsvgm']}}, 05:24:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:24:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:24:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:24:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:24:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['damp', 'tps']}, 05:24:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'tests': ['damp', 'tps']}, 05:24:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['dromaeo_dom']}, 05:24:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:24:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['basic_compositor_video']}, 05:24:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:24:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['a11yr', 05:24:43 INFO - 'ts_paint', 05:24:43 INFO - 'tpaint', 05:24:43 INFO - 'sessionrestore', 05:24:43 INFO - 'sessionrestore_no_auto_restore', 05:24:43 INFO - 'tabpaint']}, 05:24:43 INFO - 'other-e10s': {'tests': ['a11yr', 05:24:43 INFO - 'ts_paint', 05:24:43 INFO - 'tpaint', 05:24:43 INFO - 'sessionrestore', 05:24:43 INFO - 'sessionrestore_no_auto_restore', 05:24:43 INFO - 'tabpaint']}, 05:24:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:24:43 INFO - 'ts_paint', 05:24:43 INFO - 'tpaint', 05:24:43 INFO - 'sessionrestore', 05:24:43 INFO - 'sessionrestore_no_auto_restore', 05:24:43 INFO - 'tabpaint']}, 05:24:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:24:43 INFO - 'ts_paint', 05:24:43 INFO - 'tpaint', 05:24:43 INFO - 'sessionrestore', 05:24:43 INFO - 'sessionrestore_no_auto_restore', 05:24:43 INFO - 'tabpaint']}, 05:24:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['a11yr', 05:24:43 INFO - 'ts_paint', 05:24:43 INFO - 'tpaint', 05:24:43 INFO - 'sessionrestore', 05:24:43 INFO - 'sessionrestore_no_auto_restore', 05:24:43 INFO - 'tabpaint']}, 05:24:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['a11yr', 05:24:43 INFO - 'ts_paint', 05:24:43 INFO - 'tpaint', 05:24:43 INFO - 'sessionrestore', 05:24:43 INFO - 'sessionrestore_no_auto_restore', 05:24:43 INFO - 'tabpaint']}, 05:24:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['tsvgx', 05:24:43 INFO - 'tsvgr_opacity', 05:24:43 INFO - 'tart', 05:24:43 INFO - 'tscrollx', 05:24:43 INFO - 'cart']}, 05:24:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:24:43 INFO - 'tsvgr_opacity', 05:24:43 INFO - 'tart', 05:24:43 INFO - 'tscrollx', 05:24:43 INFO - 'cart']}, 05:24:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'talos_options': ['--disable-e10s'], 05:24:43 INFO - 'tests': ['tp5o']}, 05:24:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'tests': ['tp5o']}, 05:24:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'talos_options': ['--disable-e10s', 05:24:43 INFO - '--xperf_path', 05:24:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:43 INFO - 'tests': ['tp5n']}, 05:24:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:43 INFO - 'talos_options': ['--xperf_path', 05:24:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:43 INFO - 'tests': ['tp5n']}}, 05:24:43 INFO - 'talos.zip': {'path': '', 05:24:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:24:43 INFO - Running post-action listener: _resource_record_post_action 05:24:43 INFO - ##### 05:24:43 INFO - ##### Finished populate-webroot step (success) 05:24:43 INFO - ##### 05:24:43 INFO - ##### 05:24:43 INFO - ##### Running create-virtualenv step. 05:24:43 INFO - ##### 05:24:43 INFO - Running pre-action listener: _resource_record_pre_action 05:24:43 INFO - Running main action method: create_virtualenv 05:24:43 INFO - Creating virtualenv /builds/slave/test/build/venv 05:24:43 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 05:24:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:24:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:24:43 INFO - Using real prefix '/usr' 05:24:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:24:43 INFO - Installing distribute.............................................................................................................................................................................................done. 05:24:45 INFO - Installing pip.................done. 05:24:45 INFO - Return code: 0 05:24:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:45 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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:24:45 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 05:24:45 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 05:24:45 INFO - Using env: {'DISPLAY': ':0', 05:24:45 INFO - 'HOME': '/home/cltbld', 05:24:45 INFO - 'LANG': 'en_US.UTF-8', 05:24:45 INFO - 'LANGUAGE': 'en_US:en', 05:24:45 INFO - 'LOGNAME': 'cltbld', 05:24:45 INFO - 'MAIL': '/var/mail/cltbld', 05:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:45 INFO - 'MOZ_NO_REMOTE': '1', 05:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:45 INFO - 'NO_EM_RESTART': '1', 05:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:45 INFO - 'PWD': '/builds/slave/test', 05:24:45 INFO - 'SHELL': '/bin/bash', 05:24:45 INFO - 'SHLVL': '1', 05:24:45 INFO - 'TERM': 'linux', 05:24:45 INFO - 'TMOUT': '86400', 05:24:45 INFO - 'USER': 'cltbld', 05:24:45 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:45 INFO - '_': '/tools/buildbot/bin/python'} 05:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:45 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 05:24:45 INFO - Cleaning up... 05:24:45 INFO - Return code: 0 05:24:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:45 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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:24:45 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 05:24:45 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 05:24:45 INFO - Using env: {'DISPLAY': ':0', 05:24:45 INFO - 'HOME': '/home/cltbld', 05:24:45 INFO - 'LANG': 'en_US.UTF-8', 05:24:45 INFO - 'LANGUAGE': 'en_US:en', 05:24:45 INFO - 'LOGNAME': 'cltbld', 05:24:45 INFO - 'MAIL': '/var/mail/cltbld', 05:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:45 INFO - 'MOZ_NO_REMOTE': '1', 05:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:45 INFO - 'NO_EM_RESTART': '1', 05:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:45 INFO - 'PWD': '/builds/slave/test', 05:24:45 INFO - 'SHELL': '/bin/bash', 05:24:45 INFO - 'SHLVL': '1', 05:24:45 INFO - 'TERM': 'linux', 05:24:45 INFO - 'TMOUT': '86400', 05:24:45 INFO - 'USER': 'cltbld', 05:24:45 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:45 INFO - '_': '/tools/buildbot/bin/python'} 05:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:46 INFO - Downloading/unpacking psutil>=3.1.1 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:24:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:48 INFO - Installing collected packages: psutil 05:24:48 INFO - Running setup.py install for psutil 05:24:48 INFO - building 'psutil._psutil_linux' extension 05:24:48 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 05:24:48 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 05:24:48 INFO - building 'psutil._psutil_posix' extension 05:24:48 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 05:24:48 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 05:24:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:48 INFO - Successfully installed psutil 05:24:48 INFO - Cleaning up... 05:24:48 INFO - Return code: 0 05:24:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:48 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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:24:48 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 05:24:48 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 05:24:48 INFO - Using env: {'DISPLAY': ':0', 05:24:48 INFO - 'HOME': '/home/cltbld', 05:24:48 INFO - 'LANG': 'en_US.UTF-8', 05:24:48 INFO - 'LANGUAGE': 'en_US:en', 05:24:48 INFO - 'LOGNAME': 'cltbld', 05:24:48 INFO - 'MAIL': '/var/mail/cltbld', 05:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:48 INFO - 'MOZ_NO_REMOTE': '1', 05:24:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:48 INFO - 'NO_EM_RESTART': '1', 05:24:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:48 INFO - 'PWD': '/builds/slave/test', 05:24:48 INFO - 'SHELL': '/bin/bash', 05:24:48 INFO - 'SHLVL': '1', 05:24:48 INFO - 'TERM': 'linux', 05:24:48 INFO - 'TMOUT': '86400', 05:24:48 INFO - 'USER': 'cltbld', 05:24:48 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:48 INFO - '_': '/tools/buildbot/bin/python'} 05:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:24:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:24:50 INFO - Installing collected packages: mozsystemmonitor 05:24:50 INFO - Running setup.py install for mozsystemmonitor 05:24:50 INFO - Successfully installed mozsystemmonitor 05:24:50 INFO - Cleaning up... 05:24:50 INFO - Return code: 0 05:24:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:24:50 INFO - Using env: {'DISPLAY': ':0', 05:24:50 INFO - 'HOME': '/home/cltbld', 05:24:50 INFO - 'LANG': 'en_US.UTF-8', 05:24:50 INFO - 'LANGUAGE': 'en_US:en', 05:24:50 INFO - 'LOGNAME': 'cltbld', 05:24:50 INFO - 'MAIL': '/var/mail/cltbld', 05:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:50 INFO - 'MOZ_NO_REMOTE': '1', 05:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:50 INFO - 'NO_EM_RESTART': '1', 05:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:50 INFO - 'PWD': '/builds/slave/test', 05:24:50 INFO - 'SHELL': '/bin/bash', 05:24:50 INFO - 'SHLVL': '1', 05:24:50 INFO - 'TERM': 'linux', 05:24:50 INFO - 'TMOUT': '86400', 05:24:50 INFO - 'USER': 'cltbld', 05:24:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:50 INFO - '_': '/tools/buildbot/bin/python'} 05:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:51 INFO - Downloading/unpacking jsonschema==2.5.1 05:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:24:52 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 05:24:52 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 05:24:52 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 05:24:52 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 05:24:52 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 05:24:52 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 05:24:53 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:53 INFO - warning: no files found matching '*.txt' 05:24:53 INFO - no previously-included directories found matching 'build' 05:24:53 INFO - no previously-included directories found matching 'dist' 05:24:53 INFO - no previously-included directories found matching '.git*' 05:24:53 INFO - Installing collected packages: jsonschema, functools32 05:24:53 INFO - Running setup.py install for functools32 05:24:53 INFO - warning: no files found matching '*.txt' 05:24:53 INFO - no previously-included directories found matching 'build' 05:24:53 INFO - no previously-included directories found matching 'dist' 05:24:53 INFO - no previously-included directories found matching '.git*' 05:24:53 INFO - Successfully installed jsonschema functools32 05:24:53 INFO - Cleaning up... 05:24:53 INFO - Return code: 0 05:24:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:24:53 INFO - Using env: {'DISPLAY': ':0', 05:24:53 INFO - 'HOME': '/home/cltbld', 05:24:53 INFO - 'LANG': 'en_US.UTF-8', 05:24:53 INFO - 'LANGUAGE': 'en_US:en', 05:24:53 INFO - 'LOGNAME': 'cltbld', 05:24:53 INFO - 'MAIL': '/var/mail/cltbld', 05:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:53 INFO - 'MOZ_NO_REMOTE': '1', 05:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:53 INFO - 'NO_EM_RESTART': '1', 05:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:53 INFO - 'PWD': '/builds/slave/test', 05:24:53 INFO - 'SHELL': '/bin/bash', 05:24:53 INFO - 'SHLVL': '1', 05:24:53 INFO - 'TERM': 'linux', 05:24:53 INFO - 'TMOUT': '86400', 05:24:53 INFO - 'USER': 'cltbld', 05:24:53 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:53 INFO - '_': '/tools/buildbot/bin/python'} 05:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:53 INFO - Downloading/unpacking blobuploader==1.2.4 05:24:53 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 05:24:53 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 05:24:53 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 05:24:53 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 05:24:53 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 05:24:53 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 05:24:55 INFO - Downloading blobuploader-1.2.4.tar.gz 05:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:24:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:24:55 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 05:24:55 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 05:24:55 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 05:24:55 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 05:24:55 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 05:24:55 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 05:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:24:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:56 INFO - Downloading docopt-0.6.1.tar.gz 05:24:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:24:56 INFO - Installing collected packages: blobuploader, requests, docopt 05:24:56 INFO - Running setup.py install for blobuploader 05:24:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:24:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:24:56 INFO - Running setup.py install for requests 05:24:56 INFO - Running setup.py install for docopt 05:24:56 INFO - Successfully installed blobuploader requests docopt 05:24:56 INFO - Cleaning up... 05:24:56 INFO - Return code: 0 05:24:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:56 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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:24:56 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 05:24:56 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 05:24:56 INFO - Using env: {'DISPLAY': ':0', 05:24:56 INFO - 'HOME': '/home/cltbld', 05:24:56 INFO - 'LANG': 'en_US.UTF-8', 05:24:56 INFO - 'LANGUAGE': 'en_US:en', 05:24:56 INFO - 'LOGNAME': 'cltbld', 05:24:56 INFO - 'MAIL': '/var/mail/cltbld', 05:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:56 INFO - 'MOZ_NO_REMOTE': '1', 05:24:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:56 INFO - 'NO_EM_RESTART': '1', 05:24:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:56 INFO - 'PWD': '/builds/slave/test', 05:24:56 INFO - 'SHELL': '/bin/bash', 05:24:56 INFO - 'SHLVL': '1', 05:24:56 INFO - 'TERM': 'linux', 05:24:56 INFO - 'TMOUT': '86400', 05:24:56 INFO - 'USER': 'cltbld', 05:24:56 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:56 INFO - '_': '/tools/buildbot/bin/python'} 05:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:57 INFO - Running setup.py (path:/tmp/pip-AVtrEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:57 INFO - Running setup.py (path:/tmp/pip-0Fv5Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:57 INFO - Running setup.py (path:/tmp/pip-5dnYOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:57 INFO - Running setup.py (path:/tmp/pip-miQ6EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:57 INFO - Running setup.py (path:/tmp/pip-DX2Zj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:57 INFO - Running setup.py (path:/tmp/pip-fwSagG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:57 INFO - Running setup.py (path:/tmp/pip-HYALrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:57 INFO - Running setup.py (path:/tmp/pip-YxMoOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:57 INFO - Running setup.py (path:/tmp/pip-PgY9r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:57 INFO - Running setup.py (path:/tmp/pip-gkHTTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:57 INFO - Running setup.py (path:/tmp/pip-Kac2EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:57 INFO - Running setup.py (path:/tmp/pip-ziyHmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:58 INFO - Running setup.py (path:/tmp/pip-c_L9jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:58 INFO - Running setup.py (path:/tmp/pip-xhKvFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:58 INFO - Running setup.py (path:/tmp/pip-twP4No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:58 INFO - Running setup.py (path:/tmp/pip-FeZchy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:58 INFO - Running setup.py (path:/tmp/pip-lQpUa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:24:58 INFO - Running setup.py install for manifestparser 05:24:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:24:58 INFO - Running setup.py install for mozcrash 05:24:58 INFO - Running setup.py install for mozdebug 05:24:58 INFO - Running setup.py install for mozdevice 05:24:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:24:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:24:58 INFO - Running setup.py install for mozfile 05:24:58 INFO - Running setup.py install for mozhttpd 05:24:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for mozinfo 05:24:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for mozInstall 05:24:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for mozleak 05:24:59 INFO - Running setup.py install for mozlog 05:24:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for moznetwork 05:24:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for mozprocess 05:24:59 INFO - Running setup.py install for mozprofile 05:24:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for mozrunner 05:24:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:24:59 INFO - Running setup.py install for mozscreenshot 05:25:00 INFO - Running setup.py install for moztest 05:25:00 INFO - Running setup.py install for mozversion 05:25:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:25:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:25:00 INFO - Cleaning up... 05:25:00 INFO - Return code: 0 05:25:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:00 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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:25:00 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 05:25:00 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 05:25:00 INFO - Using env: {'DISPLAY': ':0', 05:25:00 INFO - 'HOME': '/home/cltbld', 05:25:00 INFO - 'LANG': 'en_US.UTF-8', 05:25:00 INFO - 'LANGUAGE': 'en_US:en', 05:25:00 INFO - 'LOGNAME': 'cltbld', 05:25:00 INFO - 'MAIL': '/var/mail/cltbld', 05:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:00 INFO - 'MOZ_NO_REMOTE': '1', 05:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:00 INFO - 'NO_EM_RESTART': '1', 05:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:00 INFO - 'PWD': '/builds/slave/test', 05:25:00 INFO - 'SHELL': '/bin/bash', 05:25:00 INFO - 'SHLVL': '1', 05:25:00 INFO - 'TERM': 'linux', 05:25:00 INFO - 'TMOUT': '86400', 05:25:00 INFO - 'USER': 'cltbld', 05:25:00 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:00 INFO - '_': '/tools/buildbot/bin/python'} 05:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:00 INFO - Running setup.py (path:/tmp/pip-jyNpMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:00 INFO - Running setup.py (path:/tmp/pip-bAU7Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:00 INFO - Running setup.py (path:/tmp/pip-jHx1Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:00 INFO - Running setup.py (path:/tmp/pip-HVh9u8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:00 INFO - Running setup.py (path:/tmp/pip-kNY33J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:00 INFO - Running setup.py (path:/tmp/pip-W2jWLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:00 INFO - Running setup.py (path:/tmp/pip-9kS1tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:00 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)) 05:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:00 INFO - Running setup.py (path:/tmp/pip-V40hWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:01 INFO - Running setup.py (path:/tmp/pip-6JVSKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:01 INFO - Running setup.py (path:/tmp/pip-wfvAn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:01 INFO - Running setup.py (path:/tmp/pip-b8UtI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:01 INFO - Running setup.py (path:/tmp/pip-Vculbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:01 INFO - Running setup.py (path:/tmp/pip-zUdECj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:01 INFO - Running setup.py (path:/tmp/pip-w9k2W4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:01 INFO - Running setup.py (path:/tmp/pip-G4QCll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:01 INFO - Running setup.py (path:/tmp/pip-7GnBPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:01 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)) 05:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:01 INFO - Running setup.py (path:/tmp/pip-r4b1ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:01 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)) 05:25:01 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)) 05:25:01 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)) 05:25:01 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)) 05:25:01 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)) 05:25:01 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)) 05:25:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:03 INFO - Downloading blessings-1.6.tar.gz 05:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:25:03 INFO - Installing collected packages: blessings 05:25:03 INFO - Running setup.py install for blessings 05:25:03 INFO - Successfully installed blessings 05:25:03 INFO - Cleaning up... 05:25:03 INFO - Return code: 0 05:25:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:25:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:04 INFO - Reading from file tmpfile_stdout 05:25:04 INFO - Current package versions: 05:25:04 INFO - argparse == 1.2.1 05:25:04 INFO - blessings == 1.6 05:25:04 INFO - blobuploader == 1.2.4 05:25:04 INFO - docopt == 0.6.1 05:25:04 INFO - functools32 == 3.2.3-2 05:25:04 INFO - jsonschema == 2.5.1 05:25:04 INFO - manifestparser == 1.1 05:25:04 INFO - mozInstall == 1.12 05:25:04 INFO - mozcrash == 0.17 05:25:04 INFO - mozdebug == 0.1 05:25:04 INFO - mozdevice == 0.48 05:25:04 INFO - mozfile == 1.2 05:25:04 INFO - mozhttpd == 0.7 05:25:04 INFO - mozinfo == 0.9 05:25:04 INFO - mozleak == 0.1 05:25:04 INFO - mozlog == 3.2 05:25:04 INFO - moznetwork == 0.27 05:25:04 INFO - mozprocess == 0.22 05:25:04 INFO - mozprofile == 0.28 05:25:04 INFO - mozrunner == 6.11 05:25:04 INFO - mozscreenshot == 0.1 05:25:04 INFO - mozsystemmonitor == 0.3 05:25:04 INFO - moztest == 0.7 05:25:04 INFO - mozversion == 1.4 05:25:04 INFO - psutil == 3.1.1 05:25:04 INFO - requests == 1.2.3 05:25:04 INFO - wsgiref == 0.1.2 05:25:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:04 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 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:25:04 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 05:25:04 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 05:25:04 INFO - Using env: {'DISPLAY': ':0', 05:25:04 INFO - 'HOME': '/home/cltbld', 05:25:04 INFO - 'LANG': 'en_US.UTF-8', 05:25:04 INFO - 'LANGUAGE': 'en_US:en', 05:25:04 INFO - 'LOGNAME': 'cltbld', 05:25:04 INFO - 'MAIL': '/var/mail/cltbld', 05:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:04 INFO - 'MOZ_NO_REMOTE': '1', 05:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:04 INFO - 'NO_EM_RESTART': '1', 05:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:04 INFO - 'PWD': '/builds/slave/test', 05:25:04 INFO - 'SHELL': '/bin/bash', 05:25:04 INFO - 'SHLVL': '1', 05:25:04 INFO - 'TERM': 'linux', 05:25:04 INFO - 'TMOUT': '86400', 05:25:04 INFO - 'USER': 'cltbld', 05:25:04 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:04 INFO - '_': '/tools/buildbot/bin/python'} 05:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 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)) 05:25:04 INFO - Cleaning up... 05:25:04 INFO - Return code: 0 05:25:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:25:04 INFO - Using env: {'DISPLAY': ':0', 05:25:04 INFO - 'HOME': '/home/cltbld', 05:25:04 INFO - 'LANG': 'en_US.UTF-8', 05:25:04 INFO - 'LANGUAGE': 'en_US:en', 05:25:04 INFO - 'LOGNAME': 'cltbld', 05:25:04 INFO - 'MAIL': '/var/mail/cltbld', 05:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:04 INFO - 'MOZ_NO_REMOTE': '1', 05:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:04 INFO - 'NO_EM_RESTART': '1', 05:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:04 INFO - 'PWD': '/builds/slave/test', 05:25:04 INFO - 'SHELL': '/bin/bash', 05:25:04 INFO - 'SHLVL': '1', 05:25:04 INFO - 'TERM': 'linux', 05:25:04 INFO - 'TMOUT': '86400', 05:25:04 INFO - 'USER': 'cltbld', 05:25:04 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:04 INFO - '_': '/tools/buildbot/bin/python'} 05:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:25:04 INFO - Cleaning up... 05:25:04 INFO - Return code: 0 05:25:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868aacbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, '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': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', '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 05:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:25:04 INFO - Using env: {'DISPLAY': ':0', 05:25:04 INFO - 'HOME': '/home/cltbld', 05:25:04 INFO - 'LANG': 'en_US.UTF-8', 05:25:04 INFO - 'LANGUAGE': 'en_US:en', 05:25:04 INFO - 'LOGNAME': 'cltbld', 05:25:04 INFO - 'MAIL': '/var/mail/cltbld', 05:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:04 INFO - 'MOZ_NO_REMOTE': '1', 05:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:04 INFO - 'NO_EM_RESTART': '1', 05:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:04 INFO - 'PWD': '/builds/slave/test', 05:25:04 INFO - 'SHELL': '/bin/bash', 05:25:04 INFO - 'SHLVL': '1', 05:25:04 INFO - 'TERM': 'linux', 05:25:04 INFO - 'TMOUT': '86400', 05:25:04 INFO - 'USER': 'cltbld', 05:25:04 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:04 INFO - '_': '/tools/buildbot/bin/python'} 05:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:04 INFO - Downloading/unpacking flake8 05:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:25:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:25:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:25:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:25:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:25:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:25:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:25:07 INFO - no previously-included directories found matching 'docs/_build' 05:25:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:25:07 INFO - Running setup.py install for pep8 05:25:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:25:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:25:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:25:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:25:07 INFO - no previously-included directories found matching 'docs/_build' 05:25:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:25:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:25:07 INFO - Cleaning up... 05:25:07 INFO - Return code: 0 05:25:07 INFO - Running post-action listener: _resource_record_post_action 05:25:07 INFO - Running post-action listener: _start_resource_monitoring 05:25:07 INFO - Starting resource monitoring. 05:25:07 INFO - ##### 05:25:07 INFO - ##### Finished create-virtualenv step (success) 05:25:07 INFO - ##### 05:25:07 INFO - ##### 05:25:07 INFO - ##### Running install step. 05:25:07 INFO - ##### 05:25:07 INFO - Running pre-action listener: _resource_record_pre_action 05:25:07 INFO - Running main action method: install 05:25:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:07 INFO - Reading from file tmpfile_stdout 05:25:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:25:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:25:08 INFO - Reading from file tmpfile_stdout 05:25:08 INFO - Output received: 05:25:08 INFO - Usage: mozinstall [options] installer 05:25:08 INFO - Options: 05:25:08 INFO - -h, --help show this help message and exit 05:25:08 INFO - -d DEST, --destination=DEST 05:25:08 INFO - Directory to install application into. [default: 05:25:08 INFO - "/builds/slave/test"] 05:25:08 INFO - --app=APP Application being installed. [default: firefox] 05:25:08 INFO - mkdir: /builds/slave/test/build/application 05:25:08 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'] 05:25:08 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 05:25:24 INFO - Reading from file tmpfile_stdout 05:25:24 INFO - Output received: 05:25:24 INFO - /builds/slave/test/build/application/firefox/firefox 05:25:24 INFO - Running post-action listener: _resource_record_post_action 05:25:24 INFO - ##### 05:25:24 INFO - ##### Finished install step (success) 05:25:24 INFO - ##### 05:25:24 INFO - ##### 05:25:24 INFO - ##### Running run-tests step. 05:25:24 INFO - ##### 05:25:24 INFO - Running pre-action listener: _resource_record_pre_action 05:25:24 INFO - Running main action method: run_tests 05:25:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:25:24 INFO - Python 2.7.3 05:25:24 INFO - Return code: 0 05:25:24 INFO - grabbing minidump binary from tooltool 05:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:24 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 0x1c4e1c0>, '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 0x1c4f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:25:24 INFO - Return code: 0 05:25:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:25:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:25:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:25:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:25:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:25:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:25:25 INFO - Return code: 0 05:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:25:25 INFO - Using env: {'DISPLAY': ':0', 05:25:25 INFO - 'HOME': '/home/cltbld', 05:25:25 INFO - 'LANG': 'en_US.UTF-8', 05:25:25 INFO - 'LANGUAGE': 'en_US:en', 05:25:25 INFO - 'LOGNAME': 'cltbld', 05:25:25 INFO - 'MAIL': '/var/mail/cltbld', 05:25:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:25:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:25 INFO - 'MOZ_NO_REMOTE': '1', 05:25:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:25:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:25 INFO - 'NO_EM_RESTART': '1', 05:25:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:25 INFO - 'PWD': '/builds/slave/test', 05:25:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:25:25 INFO - 'SHELL': '/bin/bash', 05:25:25 INFO - 'SHLVL': '1', 05:25:25 INFO - 'TERM': 'linux', 05:25:25 INFO - 'TMOUT': '86400', 05:25:25 INFO - 'USER': 'cltbld', 05:25:25 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422', 05:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:25 INFO - '_': '/tools/buildbot/bin/python'} 05:25:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:25:26 INFO - mozversion application_buildid: 20160617023006 05:25:26 INFO - mozversion application_changeset: 048bc3681be741fa5677744a0a30cff6a7358557 05:25:26 INFO - mozversion application_display_name: Nightly 05:25:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:25:26 INFO - mozversion application_name: Firefox 05:25:26 INFO - mozversion application_remotingname: firefox 05:25:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:25:26 INFO - mozversion application_vendor: Mozilla 05:25:26 INFO - mozversion application_version: 50.0a1 05:25:26 INFO - mozversion platform_buildid: 20160617023006 05:25:26 INFO - mozversion platform_changeset: 048bc3681be741fa5677744a0a30cff6a7358557 05:25:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:25:26 INFO - mozversion platform_version: 50.0a1 05:25:26 INFO - using testdate: 1466166326 05:25:26 INFO - actual date: 1466166326 05:25:26 INFO - starting webserver on 'localhost:51795' 05:25:26 INFO - SUITE-START | Running 2 tests 05:25:26 INFO - TEST-START | dromaeo_css 05:25:26 INFO - operating with platform_type : linux_ 05:25:26 INFO - Initialising browser for dromaeo_css test... 05:25:26 INFO - TEST-INFO | started process 20389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpioglny/profile http://localhost:51795/getInfo.html) 05:25:30 INFO - PROCESS | 20389 | __metrics Screen width/height:1600/1200 05:25:30 INFO - PROCESS | 20389 | colorDepth:24 05:25:30 INFO - PROCESS | 20389 | Browser inner width/height: 1024/697 05:25:30 INFO - PROCESS | 20389 | __metrics 05:25:31 INFO - PROCESS | 20389 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:32 INFO - TEST-INFO | 20389: exit 0 05:25:32 INFO - Browser initialized. 05:25:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:25:32 INFO - TEST-INFO | started process 20474 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpioglny/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:44 INFO - PROCESS | 20474 | RSS: Main: 173920256 05:25:44 INFO - PROCESS | 20474 | 05:26:36 INFO - PROCESS | 20474 | 1466166396257 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] 05:28:44 INFO - PROCESS | 20474 | Cycle 1(1): loaded http://localhost:51795/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51795/tests/dromaeo/cssquery-ext.html) 05:28:45 INFO - PROCESS | 20474 | RSS: Main: 254316544 05:28:45 INFO - PROCESS | 20474 | 05:31:47 INFO - PROCESS | 20474 | Cycle 1(1): loaded http://localhost:51795/tests/dromaeo/cssquery-ext.html (next: http://localhost:51795/tests/dromaeo/cssquery-jquery.html) 05:31:48 INFO - PROCESS | 20474 | RSS: Main: 265469952 05:31:48 INFO - PROCESS | 20474 | 05:34:49 INFO - PROCESS | 20474 | Cycle 1(1): loaded http://localhost:51795/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51795/tests/dromaeo/cssquery-mootools.html) 05:34:50 INFO - PROCESS | 20474 | RSS: Main: 254816256 05:34:50 INFO - PROCESS | 20474 | 05:37:52 INFO - PROCESS | 20474 | Cycle 1(1): loaded http://localhost:51795/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51795/tests/dromaeo/cssquery-prototype.html) 05:37:53 INFO - PROCESS | 20474 | RSS: Main: 252141568 05:37:53 INFO - PROCESS | 20474 | 05:40:55 INFO - PROCESS | 20474 | Cycle 1(1): loaded http://localhost:51795/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51795/tests/dromaeo/cssquery-yui.html) 05:40:56 INFO - PROCESS | 20474 | RSS: Main: 246587392 05:40:56 INFO - PROCESS | 20474 | 05:43:58 INFO - PROCESS | 20474 | Cycle 1(1): loaded http://localhost:51795/tests/dromaeo/cssquery-yui.html (next: http://localhost:51795/tests/dromaeo/cssquery-dojo.html) 05:43:59 INFO - PROCESS | 20474 | RSS: Main: 283377664 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | __start_tp_report 05:43:59 INFO - PROCESS | 20474 | _x_x_mozilla_page_load 05:43:59 INFO - PROCESS | 20474 | _x_x_mozilla_page_load_details 05:43:59 INFO - PROCESS | 20474 | |i|pagename|runs| 05:43:59 INFO - PROCESS | 20474 | |0;dojo.html;3059.1281484776837;3060.724534791867;3076.0618011506494;3105.409972105301;3112.393083348748;5181.637285390024;5213.139831927728;5287.762050707718;5345.599080068996;5363.597730170238;5290.68255904646;5302.787888484456;5304.655197412175;5335.33308336458;5404.135338345865;4770.284457331393;4842.539958703917;4868.221084626461;4869.318295438641;4975.253711943201;4874.960114869172;4890.119778439897;4891.4130304363525;4941.802327906879;4961.875953101166;4762.64280178988;4801.4718380978065;4834.661573689843;4835.492273311287;4862.246351815475;4854;4873.098476781806;4873.829415970424;4929.630277729185;4973.154563724175;4892.877678058041;4900.093482705711;4907.705537667752;4909.484504127051;4913.385826771653;5224.425313215544;5259.158534634478;5335.039692855285;5339.225812257201;5365.731713414352;5729.570282228808;5738.971305143489;5751.05107657236;5755.712214389264;5755.884882302331;5640.464155905182;5743.798967036176;5744.425557444264;5784.1902133701315;5796.82609521715;5231.555317797992;5285.841424757264;5295.338082739658;5369.812056578079;5380.219868119101;5898.616589921641;5914.674692891932;5917;5917.822465326047;5923.526117910556;5460.726963651801;5503.3120859892515;5695.40198279183;5699.202111704365;5701.030824759718;5601.691906945076;5622.690752008679;5641.238432811518;5643;5660.745266463019;5653.462921022497;5658.575606829504;5659.434056594308;5661.433856614306;5664.97167514168;5607.607467477237;5609.1305847593685;5664.830055098354;5667.4332566743;5670.744816483269;4302.913941721211;4348.304271316574;4379.255526560429;4390.363397307437;4393.055493069006;3904.9804750976064;3911.6283953024417;3914.178022615226;3949.4273330366937;3954.0114971257185;5265.420803711589;5398.2982212312145;5407.215953686693;5409.7295135243085;5410.215518749759;5599.412061733459;5647.6893770843;5658.179563963285;5699.401562835926;5706.971465142731;5360.65155764874;5390.622656414013;5391.083515802323;5394.406615272317;5397.217403476552;4875.878103047381;4884.902301954012;4931.5315045070665;4931.630127740362;4961.776720047606;5781.682007489629;5832.387599302097;5853.853653658693;5854.18041474194;5898.764049437975;5667.716614169358;5682.886342273214;5683.261176047253;5684.431556844283;5700.743466543932;5745.597808153389;5794.62334948227;5797.4782269595935;5833.883322333614;5857.384974577779;5628.943710562842;5638.154276858504;5639.3232812062815;5682.403347648603;5692.203091567101;5679.460451257124;5721.599488035798;5728.1694154348215;5734.168545560787;5736.110902810071;5401.756920938488;5527.474889885454;5555.972220138873;5575.749091290821;5586.02244607184;5543.445655434585;5584.636998595079;5587.7485513152815;5588.972055139699;5628.549716022627;5489.094299440548;5495.725213739217;5497.862553436036;5498.285222920995;5500.477454641803;5416.24172615842;5510.173473978935;5574.191742197278;5582.581306401955;5649;5500.944990550043;5512.448755124456;5517.5585953124455;5519.089350257163;5520.779168833202 05:43:59 INFO - PROCESS | 20474 | |1;ext.html;601.9578629495937;635.5360586771657;637.9649119298438;641.7497176101324;651.524387197346;32039.199020024513;44197;44751;44761;44882.10235795282;47304;47913.281300780516;47992.760036199856;47997.28004079933;48000.27999580009;8889.955550222256;9021.36850420471;9030.86453703195;9038.322125840556;9038.502882341501;8830.823383532357;8928.598213080395;8941.105889411072;8941.374103812735;8944.955275223614;8954.417962832395;9001.549922503882;9011.774705632379;9033.412828166181;9038.683646072388;8909.910900891005;8949.910500895037;8955.776105597348;8958.910410895873;8961.580373299204;8910.599023043978;8979.057198994142;8985;8991.100889911022;8994.145556172152;43945.78027109876;44027.33958990618;44310.11379772419;44361.334579981325;44366.556334436566;3435.8282085895853;3544.078539579728;3647.0335361129323;3651.543557055368;3999.900002499961;33522.6647733522;33941.83029084864;34073.659263407295;34760.826195869115;35222.64777352219;4356.172327257811;4379.868603941887;4380.715253508543;4430.11397720454;4633.32816741574;256350;256627;257011;257616;258218.9999999981;60824.39175608276;61325.693371532856;61514.69242653758;61592.384076159564;61610.69194653998;57558.424415755304;57758.42241577614;57978;58333;63604.36395636077;16604;17273.309067637405;17273.481795546155;18038.2784688611;18051.909740451214;13779.724405512034;13943.930280348533;13951.441942322193;13959.651008724863;13984.720305594035;16764.58088547796;16830.07434591085;18146.81853181451;18285.725714114298;18459.815401845808;6220.782272620527;6397.584157029872;6465.450436712838;6522.380373864476;6536.934630653727;997.9501024948869;1060.0088916862758;1073.6043143912882;1148.0987424871437;1165.685264978468;33050.83474582612;33608;33700.49449258263;33773.83113084468;34212.48681269783;1104.8950349716763;1106.2643342177523;1109.5007246738999;1134.2797323699344;1143.6283207957447;17344.219510122075;17371.73942390865;17400;17533.91233043852;17557.561060973323;481315;483046;483248;483553;483827;59537;61139.69430152821;61181.082283765776;61218.0817287741;61320.69339653362;1357.3077730357588;1368.5210176438168;1396.1343966740483;1397.594697537723;1408.8661577150153;31022;32038.35923281568;32175.51736723904;32205.35589288248;33085.50371744474;53754.73122634362;54035.189472157166;54117.18824217719;54209.72895135499;54251.72874135604;415669;415711;415982;416803;417347;42172;44263;44895.32657010082;45446.54553454423;45570;50808.23787643114;50938.745306274715;51053;51517.742411287705;51678;41716;42183.7890810544;43078;43261.78369108134;43802.34296485619;42083.57916420919;42467;43075.56924430715;43822.56177438185;43955.7033222516;42277;42806.35790463208;43009.354859677755;43660.34509482424;43710.781446092566;36089.45865811963;37401;37978.81417245075;38454.2309153821;38538.421923671725;28664.283392915346;28921;29094;29430;29449.263768405963 05:43:59 INFO - PROCESS | 20474 | |2;jquery.html;365.9268146370723;374.9137698329386;381.5345278759913;394.44383419378676;409.83606557377044;17143.399981000668;18740.344087956924;18832.717509237336;18834.905825470854;19316.51708707283;17913.7312940306;18919;19179;19904.402867913985;19971.700424493607;5282.5245727884485;5296.285001524793;5368.409474957752;5477.643953143053;5482.588805839558;5306.389765176996;5342.439043900393;5343.893122137555;5362.678239305635;5381.246625472437;5185.455527169649;5299.336346642667;5360.678359298455;5363.141897296433;5367.651102678333;5345.679259244438;5355.571554275649;5360.222767698662;5391.81128660495;5400.972995135019;5188.662736922088;5236.136037553799;5239.3712754469325;5239.637694199498;5259.053370393328;13706.93146534271;13891.305434728223;13969.650758731113;14121.788173177309;19651.705224421647;2266.614675505168;2278.2254033628647;2379.9881000594887;2403.42317843718;2493.2146373892165;10077;10157.984201579857;10785.245032847704;13088;13405.932970335183;1922.4424916774258;1995.4412764425983;2025.9696104558443;2039.3168288623328;2162.1459523488293;75288.62355688187;77935;78175;81148;84303.57848210781;10633;10727.892721072845;10988.560457581767;11160.609378671708;11309;15145.697086058217;15180.544583662508;15242.847571524142;15247.618809529851;15264.465743698916;8168.591570421455;8252.381071419668;8335.208155225244;8456.238938495564;8460.153984601491;8085.636146373427;8200.712975045844;8207.671693132328;8299.751007469786;8342.499450033018;4675.976620116878;4773.832915847998;4799.256115302133;4822;4879.414470263591;1924.1437560285538;1955.6773132433273;1975.0914579293685;1976.9110390032483;1979.5644958109192;408.2855003743449;419.61185903039564;423.38397631446173;424.7854833309159;851.8296340731879;11591.536338546364;11631.59289424866;11812.468438920268;11816.231944923553;11996.58011969577;442.63040361298675;447.42505879944116;448.4528874772772;449.03681602961467;463.35593525000274;10592.311499752492;10602.681919542427;11005.17461190414;11113.666590002313;11418.885811141949;464337;468321;469465;469986;486819;74582.8812567812;89657.55171224102;90464.54767726119;93672;95315.52342238244;1049.160671462842;1065.099990508035;1084.8698156221146;1121.3720316622716;1123.6179698902413;17693;17700.822991770434;18037.819621803614;18059.819401805813;18141.365052223107;39919.40120898247;42733.704621490404;43035;44170.337444938996;44459.777701111285;60906;61209.69395152996;62112.68943655253;62191.37808621856;62640.68679656572;8597.097304783158;8664.653413863374;8676.436031657922;8741.737747867573;8790.252828509392;9975.850362244428;12453.626391208278;13236.602901912958;13472.663183420493;13687.384067717176;11618.302901825917;12105.939470302592;12251.69370765738;12374.505019799468;12442.81335779946;11336.6032188873;11874.168808183344;12003.75992480163;12040.578579749666;12340.321282329558;11483.540658373571;12328.32194229326;12551.87448125507;12583.496660133493;12647.810282845581;8519.23326900582;8774.254188393803;8906.198442140238;8925.598348074222;9233.769155771159;8937.865932011155;11568.363739994382;12025.398730063636;12059.879401206226;12097.334646594521 05:43:59 INFO - PROCESS | 20474 | |3;mootools.html;1309.6856754378953;1448.4278709909584;1516.0979217365666;1602.9679406411872;1772.592303770132;8084.797880053002;8458.32333413327;8458.407911446202;8740.4755714657;8756.824863502727;8427.70503032394;8628.223459888608;8642.308615310776;8656.480611163319;8669.046404895456;1905.237904838062;1955.1690531524116;2106.6102770987377;2145.8819764912923;2170.8697478151284;2195.802377785999;2213.800757931786;2215.4350640586626;2218.0794970087395;2218.1792736687366;2128.968065479019;2137.476318302018;2166.880821554814;2168.2627906511784;2168.3711723600136;1894.4127320530679;2010.9195632174842;2072.5544008038346;2074.771775104737;2101.3170719516143;2218.8351115664245;2223.6998005269243;2240.798328150458;2243.181238847809;2244.012634440841;8683.869741953877;8737.956310218471;8795.340349473816;8804.779880502974;8806.295496360275;4812.759362031919;4943.283223932545;4952.554270115671;4957.380327459067;4965.081459929925;3073.9538906916414;3145.779795414322;3165;3211.775175737699;3212.341469998668;1018.221060888421;1026.7843752811918;1038.6624347087152;1039.9376037437778;1054.0724162736742;11994.400279985966;11995.760084798343;11998.64004079879;12005;12009.819852702216;4371.300591905344;4413.624841888475;4439.467263928284;4517.706349087299;4520.186366454004;10052.648157314605;10053.145482634058;10071.496425178711;10075.59697612102;10101.040401161878;4946.950530494721;4982.551570358685;4983.6013118950395;4984.40187177541;4990.251462280687;4103.158852435244;4427.8228870844805;4521.819127234874;4536.56902594253;4567.269236922077;3587.2466781976077;3633.818309084535;3670.8898733037518;3676.28312479064;3701.851925922987;1138.7153211697075;1138.9886101139048;1141.7145713571608;1144.3019757947645;1150.7065698246893;986.5807032011367;1013.614826365991;1022.3610243597751;1031.2265800649513;1039.9792004159874;5838.737256823453;5914.76340946366;5973.731182096816;6132.662703551258;6375.266844312963;925.0703043441298;938.5260443476001;969.539468752337;974.4202199691173;1000.4397537379091;4001.7198796084576;4194.3918131870705;4235.089455767024;4262.573742625775;4303.33298338758;10400.47997600117;10775.245732798785;10820.02619764225;10893.618723344645;10919.727006824734;10692;10709.785804284027;10712.732181695364;10717.732056698645;10802.891971080346;7650.196729343562;8095.380923815261;8226.958865205635;8230.341572674171;8236.052853921876;6054.606450580698;6218.53360997936;6246.937530624636;6353;6363.077353783583;9996.950015250168;10091.091801737584;10210.744731381776;10244.641437549948;10254.948725256327;10198.133158681298;10300.948495257475;10540.10409115203;10571.471426428494;10572.788544228919;7016.649167541705;7611;7777.611119444118;7857.4499785014505;7950.840983180188;8609.569521524025;8657.39398242117;8675.089115643019;8731.869021964803;8740.388172827841;7440.962795185989;7628.580428076509;7648.273414025659;7700.768976930701;7783.260590243918;7621.657025433758;7624.085109786861;7696.307332339892;7770;7809.1409944907155;7089.82275443118;7140.642967851691;7181.497295189287;7192.604406757679;7196.352328290479;5242.039352448152;5245.816396426259;5266.736663166903;5295.152775555893;5463.125899856004;5181.5077567631015;5342.973285133549;5356.866078348073;5397.919031214614;5517.144842549411 05:43:59 INFO - PROCESS | 20474 | |4;prototype.html;289.44282256655947;300.7383576288629;300.9789314747968;301.92451887028244;308.8471206752657;5395.59533035022;5410.540104091147;5487.780488780449;5496.7526461309235;5505.917411238835;5115.44227886057;5202.8179013734525;5206.687598744069;5247.396549396821;5356.491133342326;3998.5001874765653;4054.209429161315;4081.693872959525;4114.732542384751;4122.505299364066;4121.690873184508;4125.690573207022;4127.979360103195;4139.668826493873;4156.688248381369;4038.1519880825063;4052.9594704053025;4076.429299898017;4100.487439070116;4121.690873184508;4056.424170651628;4057.504634649699;4062.2687916175078;4106.917861642781;4120.938185927213;3598.9100272493133;3617;3621.529201203853;3638.7991962652527;3655.94516082259;5120.180771076647;5251.343582052244;5276.94199262288;5281.445448227958;5306.363236411656;4568.360429539867;4620.567624036559;4656.254999200146;4710.905781884343;4715.127701375222;5554.19464177696;5557.138643510262;5569;5578.183017576098;5638.548916086703;3838.520838111942;3892.5718171001167;3910.19840932611;3927.8428862845453;3968.384900340423;5845.503132233765;5873.676947767871;5876;5882.8822523720355;5884.617499862496;3974;3974.20515896816;3984.322665146932;3986.5216174059296;3994.7603143811466;4054.351303791438;4058.1072164123843;4060.3503439450137;4068.959310406917;4072.7352722073565;4511.293204116022;4595.333676616939;4637.373954516097;4678.69588476748;4694.436667599842;5583.69289689071;5594.790057927096;5606.177992152485;5624.803131890446;5646.830595082154;1337.732453509302;1355.3629793997002;1380.4616199682132;1392.1438315435935;1397.5457976157788;849.9320054395633;863.2101627011215;892.8616064510011;894.2622336572354;900.594732370436;4771.373755945437;4855;4879.463259041502;4882.975585122052;4889.8216159496615;5134.358205224347;5136;5148.841510660146;5158.200478925773;5159;4656.022235330618;4781.780645935264;4818.542238487338;4821.300911367832;4903.8528844134735;2726.768224700883;2733.4259805440684;2733.9589906151423;2738.6850512191354;2745.697973222944;5133.330668052592;5202.66182698131;5215.765290561933;5237.450067742909;5253.579713622901;4789.664723469394;5208.8437346879655;5209.557187639018;5224.622796627601;5240.68555886648;5124.385818467171;5144.15121504955;5185.818496352609;5188.844334669966;5242.423333433319;5073.34046573943;5091.8614182044375;5105.7191854448365;5124.538791508782;5159.509846564571;4684.016356565159;4990.700557966534;5062.670926389773;5127.307813445286;5160.277561141368;5182.818601348935;5244.977229440223;5266.210068489604;5343.385510666323;5354.965113256937;3605.5853576839;3664.929314937159;3708.9072773180887;3712.276106159381;3716.95925140961;4919.729414882216;5081.72050537224;5127.20547808279;5175.844724658266;5554.694491802989;5469.535089517276;5570.5265052470995;5603.646468483328;5665.745041473061;5702;5648.915266271091;5662.320521537442;5667.829965101054;5688.800891968761;5695.943040569541;5443.846000398931;5468.72656367188;5516.227728118147;5596.2298662160865;5602.719864006702;5570.4151064139305;5612.466815652507;5664.348599911063;5688.975984322861;5724.656520608777;5417.918731218956;5567.137093750475;5581.386047534849;5581.413951535031;5609.49514543693 05:43:59 INFO - PROCESS | 20474 | |5;yui.html;748.390062099389;759.8860170974353;787.1341524323242;793.2107552984781;830.2154464031493;15195.088294702326;16176.676466470664;16501.174941252935;16517.83482165178;16541.66916661666;11705.70735731607;14683.559493215194;14839.887008474354;14848.554543363716;14859.405623775036;799.0611032037361;817.513579420247;817.9100298967109;823.567626995826;828.2587084559316;805.9677612895478;809.8947136872198;811.7524155132683;811.9188081191893;812.2364976921705;711.4450728431832;827.8882350882614;834.2325060943946;839.9622017009219;840.9369297302696;852.1010334097525;852.1904191018496;852.9317654587618;853.1596377568121;854.4275335525182;613.4019331152135;614.7571709174896;619.1239396254294;620.0234630457003;622.3993845938691;15225.771613425808;15253.38986440541;15296.770548441782;15318.463853765064;15456.690866182613;14345;14421.35103920326;14576.635584110376;14691.191984440851;14696.868954001717;13076.86923130766;13244.668883277898;13259.86740132596;13343.73312533744;13611.251381174032;4477.306017567283;4483.932741008887;4490.775461226958;4530.07133537624;4575.49669536354;78962.60518697427;79622.60188699077;79654.60172699158;80065.19934800635;80470.59764701198;11197.888021119685;11212.66362009141;11437.714057148638;11607.76784464306;11873.287602743863;21432.686386300906;21714.56570868574;21743.891280543812;21795.128194872028;22354;8273.751787446266;8285.917140828515;8380.53907035119;8474.322054235647;8732.78168045804;4589.495155532889;4675.438947326343;4691.390119284471;4829.082474329866;4837.250226214872;3362.8486718097743;3417.9316413671586;3428.7257019438384;3434.9828250858586;3470.6876381125317;590.2621722846442;594.9553783466257;601.6029420582395;660.8182749743859;663.3366633366634;639.1626968671055;689.7310049080862;709.2234003765868;710.2897102897103;718.7448455798117;10007.749806254757;10293.228007899437;10352.741181470523;10448.947755261175;10513.474326283655;693.5006795107514;712.4585315160515;713.6289129652552;768.9846203075907;776.3711393771064;4642.698224615389;4650.767461626905;4719.764011799396;4847.95152048475;4864.6594738368685;124508.37745811213;127411.36294318654;127772;128127.35936320259;129857;83399;103519.4824025875;103767.4811625937;104032;106329.46835265774;71178.64410678016;75212.62393687997;94143.52928235452;94243.52878235564;97056.51471742596;8889.19997200255;9030;9037.277017838689;9151.496667683341;9270.953645231732;42818.5718142827;51457;53188;54034.729826350616;54725;71301;81945.59027205064;82145.58927205326;84464;86354.1364586346;11899.564954622267;12280.75438491243;12312.199707019015;12377.876221238033;13247.072704910564;13999.650008749863;14162.858371416567;14366.281685915454;14491.49279775245;14508.419663213775;9617.326787124834;12773.552925647558;12980.415881285177;13867.653308667364;13942.442302307794;14420.639484012983;14579.562613121623;14663.780043299146;14753.704925901207;14783.482578110144;14079.788803167756;14218.928905355408;14274.785878212042;14504.927475362556;14609.050411723203;11252.887471125512;11279.266847654877;11641.243319184226;11654.80249646081;11837.289762614271;11730.519383919655;11780.52877884875;11798.469068891749;12149.1495595311;12177 05:43:59 INFO - PROCESS | 20474 | __end_tp_report 05:43:59 INFO - PROCESS | 20474 | __start_cc_report 05:43:59 INFO - PROCESS | 20474 | _x_x_mozilla_cycle_collect,926 05:43:59 INFO - PROCESS | 20474 | __end_cc_report 05:43:59 INFO - PROCESS | 20474 | __startTimestamp1466167439032__endTimestamp 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | ------- Summary: start ------- 05:43:59 INFO - PROCESS | 20474 | Number of tests: 6 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | [#0] dojo.html Cycles:165 Average:5293.23 Median:5500.48 stddev:590.30 (10.7%) stddev-sans-first:565.50 05:43:59 INFO - PROCESS | 20474 | Values: 3059.1 3060.7 3076.1 3105.4 3112.4 5181.6 5213.1 5287.8 5345.6 5363.6 5290.7 5302.8 5304.7 5335.3 5404.1 4770.3 4842.5 4868.2 4869.3 4975.3 4875.0 4890.1 4891.4 4941.8 4961.9 4762.6 4801.5 4834.7 4835.5 4862.2 4854.0 4873.1 4873.8 4929.6 4973.2 4892.9 4900.1 4907.7 4909.5 4913.4 5224.4 5259.2 5335.0 5339.2 5365.7 5729.6 5739.0 5751.1 5755.7 5755.9 5640.5 5743.8 5744.4 5784.2 5796.8 5231.6 5285.8 5295.3 5369.8 5380.2 5898.6 5914.7 5917.0 5917.8 5923.5 5460.7 5503.3 5695.4 5699.2 5701.0 5601.7 5622.7 5641.2 5643.0 5660.7 5653.5 5658.6 5659.4 5661.4 5665.0 5607.6 5609.1 5664.8 5667.4 5670.7 4302.9 4348.3 4379.3 4390.4 4393.1 3905.0 3911.6 3914.2 3949.4 3954.0 5265.4 5398.3 5407.2 5409.7 5410.2 5599.4 5647.7 5658.2 5699.4 5707.0 5360.7 5390.6 5391.1 5394.4 5397.2 4875.9 4884.9 4931.5 4931.6 4961.8 5781.7 5832.4 5853.9 5854.2 5898.8 5667.7 5682.9 5683.3 5684.4 5700.7 5745.6 5794.6 5797.5 5833.9 5857.4 5628.9 5638.2 5639.3 5682.4 5692.2 5679.5 5721.6 5728.2 5734.2 5736.1 5401.8 5527.5 5556.0 5575.7 5586.0 5543.4 5584.6 5587.7 5589.0 5628.5 5489.1 5495.7 5497.9 5498.3 5500.5 5416.2 5510.2 5574.2 5582.6 5649.0 5500.9 5512.4 5517.6 5519.1 5520.8 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | [#1] ext.html Cycles:180 Average:56879.14 Median:24091.53 stddev:104847.61 (435.2%) stddev-sans-first:105056.60 05:43:59 INFO - PROCESS | 20474 | Values: 602.0 635.5 638.0 641.7 651.5 32039.2 44197.0 44751.0 44761.0 44882.1 47304.0 47913.3 47992.8 47997.3 48000.3 8890.0 9021.4 9030.9 9038.3 9038.5 8830.8 8928.6 8941.1 8941.4 8945.0 8954.4 9001.5 9011.8 9033.4 9038.7 8909.9 8949.9 8955.8 8958.9 8961.6 8910.6 8979.1 8985.0 8991.1 8994.1 43945.8 44027.3 44310.1 44361.3 44366.6 3435.8 3544.1 3647.0 3651.5 3999.9 33522.7 33941.8 34073.7 34760.8 35222.6 4356.2 4379.9 4380.7 4430.1 4633.3 256350.0 256627.0 257011.0 257616.0 258219.0 60824.4 61325.7 61514.7 61592.4 61610.7 57558.4 57758.4 57978.0 58333.0 63604.4 16604.0 17273.3 17273.5 18038.3 18051.9 13779.7 13943.9 13951.4 13959.7 13984.7 16764.6 16830.1 18146.8 18285.7 18459.8 6220.8 6397.6 6465.5 6522.4 6536.9 998.0 1060.0 1073.6 1148.1 1165.7 33050.8 33608.0 33700.5 33773.8 34212.5 1104.9 1106.3 1109.5 1134.3 1143.6 17344.2 17371.7 17400.0 17533.9 17557.6 481315.0 483046.0 483248.0 483553.0 483827.0 59537.0 61139.7 61181.1 61218.1 61320.7 1357.3 1368.5 1396.1 1397.6 1408.9 31022.0 32038.4 32175.5 32205.4 33085.5 53754.7 54035.2 54117.2 54209.7 54251.7 415669.0 415711.0 415982.0 416803.0 417347.0 42172.0 44263.0 44895.3 45446.5 45570.0 50808.2 50938.7 51053.0 51517.7 51678.0 41716.0 42183.8 43078.0 43261.8 43802.3 42083.6 42467.0 43075.6 43822.6 43955.7 42277.0 42806.4 43009.4 43660.3 43710.8 36089.5 37401.0 37978.8 38454.2 38538.4 28664.3 28921.0 29094.0 29430.0 29449.3 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | [#2] jquery.html Cycles:180 Average:28065.71 Median:387.99 stddev:77927.14 (20084.9%) stddev-sans-first:78117.99 05:43:59 INFO - PROCESS | 20474 | Values: 365.9 374.9 381.5 394.4 409.8 17143.4 18740.3 18832.7 18834.9 19316.5 17913.7 18919.0 19179.0 19904.4 19971.7 5282.5 5296.3 5368.4 5477.6 5482.6 5306.4 5342.4 5343.9 5362.7 5381.2 5185.5 5299.3 5360.7 5363.1 5367.7 5345.7 5355.6 5360.2 5391.8 5401.0 5188.7 5236.1 5239.4 5239.6 5259.1 13706.9 13891.3 13969.7 14121.8 19651.7 2266.6 2278.2 2380.0 2403.4 2493.2 10077.0 10158.0 10785.2 13088.0 13405.9 1922.4 1995.4 2026.0 2039.3 2162.1 75288.6 77935.0 78175.0 81148.0 84303.6 10633.0 10727.9 10988.6 11160.6 11309.0 15145.7 15180.5 15242.8 15247.6 15264.5 8168.6 8252.4 8335.2 8456.2 8460.2 8085.6 8200.7 8207.7 8299.8 8342.5 4676.0 4773.8 4799.3 4822.0 4879.4 1924.1 1955.7 1975.1 1976.9 1979.6 408.3 419.6 423.4 424.8 851.8 11591.5 11631.6 11812.5 11816.2 11996.6 442.6 447.4 448.5 449.0 463.4 10592.3 10602.7 11005.2 11113.7 11418.9 464337.0 468321.0 469465.0 469986.0 486819.0 74582.9 89657.6 90464.5 93672.0 95315.5 1049.2 1065.1 1084.9 1121.4 1123.6 17693.0 17700.8 18037.8 18059.8 18141.4 39919.4 42733.7 43035.0 44170.3 44459.8 60906.0 61209.7 62112.7 62191.4 62640.7 8597.1 8664.7 8676.4 8741.7 8790.3 9975.9 12453.6 13236.6 13472.7 13687.4 11618.3 12105.9 12251.7 12374.5 12442.8 11336.6 11874.2 12003.8 12040.6 12340.3 11483.5 12328.3 12551.9 12583.5 12647.8 8519.2 8774.3 8906.2 8925.6 9233.8 8937.9 11568.4 12025.4 12059.9 12097.3 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | [#3] mootools.html Cycles:180 Average:5724.46 Median:4392.46 stddev:3309.36 (75.3%) stddev-sans-first:3302.01 05:43:59 INFO - PROCESS | 20474 | Values: 1309.7 1448.4 1516.1 1603.0 1772.6 8084.8 8458.3 8458.4 8740.5 8756.8 8427.7 8628.2 8642.3 8656.5 8669.0 1905.2 1955.2 2106.6 2145.9 2170.9 2195.8 2213.8 2215.4 2218.1 2218.2 2129.0 2137.5 2166.9 2168.3 2168.4 1894.4 2010.9 2072.6 2074.8 2101.3 2218.8 2223.7 2240.8 2243.2 2244.0 8683.9 8738.0 8795.3 8804.8 8806.3 4812.8 4943.3 4952.6 4957.4 4965.1 3074.0 3145.8 3165.0 3211.8 3212.3 1018.2 1026.8 1038.7 1039.9 1054.1 11994.4 11995.8 11998.6 12005.0 12009.8 4371.3 4413.6 4439.5 4517.7 4520.2 10052.6 10053.1 10071.5 10075.6 10101.0 4947.0 4982.6 4983.6 4984.4 4990.3 4103.2 4427.8 4521.8 4536.6 4567.3 3587.2 3633.8 3670.9 3676.3 3701.9 1138.7 1139.0 1141.7 1144.3 1150.7 986.6 1013.6 1022.4 1031.2 1040.0 5838.7 5914.8 5973.7 6132.7 6375.3 925.1 938.5 969.5 974.4 1000.4 4001.7 4194.4 4235.1 4262.6 4303.3 10400.5 10775.2 10820.0 10893.6 10919.7 10692.0 10709.8 10712.7 10717.7 10802.9 7650.2 8095.4 8227.0 8230.3 8236.1 6054.6 6218.5 6246.9 6353.0 6363.1 9997.0 10091.1 10210.7 10244.6 10254.9 10198.1 10300.9 10540.1 10571.5 10572.8 7016.6 7611.0 7777.6 7857.4 7950.8 8609.6 8657.4 8675.1 8731.9 8740.4 7441.0 7628.6 7648.3 7700.8 7783.3 7621.7 7624.1 7696.3 7770.0 7809.1 7089.8 7140.6 7181.5 7192.6 7196.4 5242.0 5245.8 5266.7 5295.2 5463.1 5181.5 5343.0 5356.9 5397.9 5517.1 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | [#4] prototype.html Cycles:180 Average:4502.25 Median:5122.28 stddev:1324.45 (25.9%) stddev-sans-first:1289.87 05:43:59 INFO - PROCESS | 20474 | Values: 289.4 300.7 301.0 301.9 308.8 5395.6 5410.5 5487.8 5496.8 5505.9 5115.4 5202.8 5206.7 5247.4 5356.5 3998.5 4054.2 4081.7 4114.7 4122.5 4121.7 4125.7 4128.0 4139.7 4156.7 4038.2 4053.0 4076.4 4100.5 4121.7 4056.4 4057.5 4062.3 4106.9 4120.9 3598.9 3617.0 3621.5 3638.8 3655.9 5120.2 5251.3 5276.9 5281.4 5306.4 4568.4 4620.6 4656.3 4710.9 4715.1 5554.2 5557.1 5569.0 5578.2 5638.5 3838.5 3892.6 3910.2 3927.8 3968.4 5845.5 5873.7 5876.0 5882.9 5884.6 3974.0 3974.2 3984.3 3986.5 3994.8 4054.4 4058.1 4060.4 4069.0 4072.7 4511.3 4595.3 4637.4 4678.7 4694.4 5583.7 5594.8 5606.2 5624.8 5646.8 1337.7 1355.4 1380.5 1392.1 1397.5 849.9 863.2 892.9 894.3 900.6 4771.4 4855.0 4879.5 4883.0 4889.8 5134.4 5136.0 5148.8 5158.2 5159.0 4656.0 4781.8 4818.5 4821.3 4903.9 2726.8 2733.4 2734.0 2738.7 2745.7 5133.3 5202.7 5215.8 5237.5 5253.6 4789.7 5208.8 5209.6 5224.6 5240.7 5124.4 5144.2 5185.8 5188.8 5242.4 5073.3 5091.9 5105.7 5124.5 5159.5 4684.0 4990.7 5062.7 5127.3 5160.3 5182.8 5245.0 5266.2 5343.4 5355.0 3605.6 3664.9 3708.9 3712.3 3717.0 4919.7 5081.7 5127.2 5175.8 5554.7 5469.5 5570.5 5603.6 5665.7 5702.0 5648.9 5662.3 5667.8 5688.8 5695.9 5443.8 5468.7 5516.2 5596.2 5602.7 5570.4 5612.5 5664.3 5689.0 5724.7 5417.9 5567.1 5581.4 5581.4 5609.5 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - PROCESS | 20474 | [#5] yui.html Cycles:180 Average:21581.75 Median:4487.35 stddev:31746.04 (707.5%) stddev-sans-first:31796.55 05:43:59 INFO - PROCESS | 20474 | Values: 748.4 759.9 787.1 793.2 830.2 15195.1 16176.7 16501.2 16517.8 16541.7 11705.7 14683.6 14839.9 14848.6 14859.4 799.1 817.5 817.9 823.6 828.3 806.0 809.9 811.8 811.9 812.2 711.4 827.9 834.2 840.0 840.9 852.1 852.2 852.9 853.2 854.4 613.4 614.8 619.1 620.0 622.4 15225.8 15253.4 15296.8 15318.5 15456.7 14345.0 14421.4 14576.6 14691.2 14696.9 13076.9 13244.7 13259.9 13343.7 13611.3 4477.3 4483.9 4490.8 4530.1 4575.5 78962.6 79622.6 79654.6 80065.2 80470.6 11197.9 11212.7 11437.7 11607.8 11873.3 21432.7 21714.6 21743.9 21795.1 22354.0 8273.8 8285.9 8380.5 8474.3 8732.8 4589.5 4675.4 4691.4 4829.1 4837.3 3362.8 3417.9 3428.7 3435.0 3470.7 590.3 595.0 601.6 660.8 663.3 639.2 689.7 709.2 710.3 718.7 10007.7 10293.2 10352.7 10448.9 10513.5 693.5 712.5 713.6 769.0 776.4 4642.7 4650.8 4719.8 4848.0 4864.7 124508.4 127411.4 127772.0 128127.4 129857.0 83399.0 103519.5 103767.5 104032.0 106329.5 71178.6 75212.6 94143.5 94243.5 97056.5 8889.2 9030.0 9037.3 9151.5 9271.0 42818.6 51457.0 53188.0 54034.7 54725.0 71301.0 81945.6 82145.6 84464.0 86354.1 11899.6 12280.8 12312.2 12377.9 13247.1 13999.7 14162.9 14366.3 14491.5 14508.4 9617.3 12773.6 12980.4 13867.7 13942.4 14420.6 14579.6 14663.8 14753.7 14783.5 14079.8 14218.9 14274.8 14504.9 14609.1 11252.9 11279.3 11641.2 11654.8 11837.3 11730.5 11780.5 11798.5 12149.1 12177.0 05:43:59 INFO - PROCESS | 20474 | -------- Summary: end -------- 05:43:59 INFO - PROCESS | 20474 | 05:43:59 INFO - TEST-INFO | 20474: exit 0 05:43:59 INFO - TEST-OK | dromaeo_css | took 1113670ms 05:43:59 INFO - TEST-START | kraken 05:43:59 INFO - operating with platform_type : linux_ 05:43:59 INFO - Initialising browser for kraken test... 05:43:59 INFO - TEST-INFO | started process 20655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfOj8mg/profile http://localhost:51795/getInfo.html) 05:44:04 INFO - PROCESS | 20655 | __metrics Screen width/height:1600/1200 05:44:04 INFO - PROCESS | 20655 | colorDepth:24 05:44:04 INFO - PROCESS | 20655 | Browser inner width/height: 1024/697 05:44:04 INFO - PROCESS | 20655 | __metrics 05:44:05 INFO - PROCESS | 20655 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:44:05 INFO - TEST-INFO | 20655: exit 0 05:44:05 INFO - Browser initialized. 05:44:05 INFO - Running cycle 1/1 for kraken test... 05:44:05 INFO - TEST-INFO | started process 20735 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfOj8mg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:44:17 INFO - PROCESS | 20735 | RSS: Main: 174022656 05:44:17 INFO - PROCESS | 20735 | 05:44:20 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51795/tests/kraken/driver.html?testName=audio-beat-detection) 05:44:21 INFO - PROCESS | 20735 | RSS: Main: 233656320 05:44:21 INFO - PROCESS | 20735 | 05:44:23 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51795/tests/kraken/driver.html?testName=audio-dft) 05:44:24 INFO - PROCESS | 20735 | RSS: Main: 221073408 05:44:24 INFO - PROCESS | 20735 | 05:44:27 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51795/tests/kraken/driver.html?testName=audio-fft) 05:44:28 INFO - PROCESS | 20735 | RSS: Main: 287141888 05:44:28 INFO - PROCESS | 20735 | 05:44:30 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51795/tests/kraken/driver.html?testName=audio-oscillator) 05:44:31 INFO - PROCESS | 20735 | RSS: Main: 198053888 05:44:31 INFO - PROCESS | 20735 | 05:44:33 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51795/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:44:33 INFO - PROCESS | 20735 | RSS: Main: 202125312 05:44:33 INFO - PROCESS | 20735 | 05:44:36 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51795/tests/kraken/driver.html?testName=imaging-darkroom) 05:44:37 INFO - PROCESS | 20735 | RSS: Main: 238796800 05:44:37 INFO - PROCESS | 20735 | 05:44:40 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51795/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:41 INFO - PROCESS | 20735 | RSS: Main: 238542848 05:44:41 INFO - PROCESS | 20735 | 05:44:44 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51795/tests/kraken/driver.html?testName=json-parse-financial) 05:44:44 INFO - PROCESS | 20735 | RSS: Main: 238481408 05:44:44 INFO - PROCESS | 20735 | 05:44:46 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51795/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:47 INFO - PROCESS | 20735 | RSS: Main: 225673216 05:44:47 INFO - PROCESS | 20735 | 05:44:48 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:49 INFO - PROCESS | 20735 | RSS: Main: 228200448 05:44:49 INFO - PROCESS | 20735 | 05:44:51 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:44:52 INFO - PROCESS | 20735 | RSS: Main: 237178880 05:44:52 INFO - PROCESS | 20735 | 05:44:54 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:55 INFO - PROCESS | 20735 | RSS: Main: 241029120 05:44:55 INFO - PROCESS | 20735 | 05:44:58 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:58 INFO - PROCESS | 20735 | RSS: Main: 240459776 05:44:58 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | Cycle 1(1): loaded http://localhost:51795/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51795/tests/kraken/driver.html?testName=ai-astar) 05:45:00 INFO - PROCESS | 20735 | RSS: Main: 283713536 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | __start_tp_report 05:45:00 INFO - PROCESS | 20735 | _x_x_mozilla_page_load 05:45:00 INFO - PROCESS | 20735 | _x_x_mozilla_page_load_details 05:45:00 INFO - PROCESS | 20735 | |i|pagename|runs| 05:45:00 INFO - PROCESS | 20735 | |0;ai-astar;111;105;110;110;109;110;111;111;110;110 05:45:00 INFO - PROCESS | 20735 | |1;audio-beat-detection;158;126;128;181;132;129;167;126;170;134 05:45:00 INFO - PROCESS | 20735 | |2;audio-dft;161;162;160;161;161;161;162;161;162;161 05:45:00 INFO - PROCESS | 20735 | |3;audio-fft;75;76;74;119;75;76;75;76;98;75 05:45:00 INFO - PROCESS | 20735 | |4;audio-oscillator;87;85;111;85;85;85;111;85;85;133 05:45:00 INFO - PROCESS | 20735 | |5;imaging-gaussian-blur;116;117;117;117;117;116;117;117;117;117 05:45:00 INFO - PROCESS | 20735 | |6;imaging-darkroom;146;147;146;145;145;146;145;145;146;149 05:45:00 INFO - PROCESS | 20735 | |7;imaging-desaturate;96;96;96;96;95;96;96;96;96;96 05:45:00 INFO - PROCESS | 20735 | |8;json-parse-financial;55;55;56;55;56;56;55;56;55;55 05:45:00 INFO - PROCESS | 20735 | |9;json-stringify-tinderbox;62;62;62;62;62;63;62;61;62;63 05:45:00 INFO - PROCESS | 20735 | |10;stanford-crypto-aes;86;81;79;84;80;80;82;79;108;86 05:45:00 INFO - PROCESS | 20735 | |11;stanford-crypto-ccm;123;123;129;121;122;121;137;137;135;134 05:45:00 INFO - PROCESS | 20735 | |12;stanford-crypto-pbkdf2;163;164;160;166;166;159;160;160;162;161 05:45:00 INFO - PROCESS | 20735 | |13;stanford-crypto-sha256-iterative;66;62;66;64;62;63;62;63;62;62 05:45:00 INFO - PROCESS | 20735 | __end_tp_report 05:45:00 INFO - PROCESS | 20735 | __start_cc_report 05:45:00 INFO - PROCESS | 20735 | _x_x_mozilla_cycle_collect,1619 05:45:00 INFO - PROCESS | 20735 | __end_cc_report 05:45:00 INFO - PROCESS | 20735 | __startTimestamp1466167500539__endTimestamp 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | ------- Summary: start ------- 05:45:00 INFO - PROCESS | 20735 | Number of tests: 14 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.77 (1.6%) stddev-sans-first:1.81 05:45:00 INFO - PROCESS | 20735 | Values: 111.0 105.0 110.0 110.0 109.0 110.0 111.0 111.0 110.0 110.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:146.00 stddev:21.42 (14.7%) stddev-sans-first:22.21 05:45:00 INFO - PROCESS | 20735 | Values: 158.0 126.0 128.0 181.0 132.0 129.0 167.0 126.0 170.0 134.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 05:45:00 INFO - PROCESS | 20735 | Values: 161.0 162.0 160.0 161.0 161.0 161.0 162.0 161.0 162.0 161.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.50 stddev:14.88 (19.7%) stddev-sans-first:15.57 05:45:00 INFO - PROCESS | 20735 | Values: 75.0 76.0 74.0 119.0 75.0 76.0 75.0 76.0 98.0 75.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:85.00 stddev:17.06 (20.1%) stddev-sans-first:17.84 05:45:00 INFO - PROCESS | 20735 | Values: 87.0 85.0 111.0 85.0 85.0 85.0 111.0 85.0 85.0 133.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 05:45:00 INFO - PROCESS | 20735 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.25 (0.9%) stddev-sans-first:1.32 05:45:00 INFO - PROCESS | 20735 | Values: 146.0 147.0 146.0 145.0 145.0 146.0 145.0 145.0 146.0 149.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:45:00 INFO - PROCESS | 20735 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 05:45:00 INFO - PROCESS | 20735 | Values: 55.0 55.0 56.0 55.0 56.0 56.0 55.0 56.0 55.0 55.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 05:45:00 INFO - PROCESS | 20735 | Values: 62.0 62.0 62.0 62.0 62.0 63.0 62.0 61.0 62.0 63.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:81.50 stddev:8.67 (10.6%) stddev-sans-first:9.18 05:45:00 INFO - PROCESS | 20735 | Values: 86.0 81.0 79.0 84.0 80.0 80.0 82.0 79.0 108.0 86.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:131.50 stddev:6.92 (5.3%) stddev-sans-first:7.08 05:45:00 INFO - PROCESS | 20735 | Values: 123.0 123.0 129.0 121.0 122.0 121.0 137.0 137.0 135.0 134.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.50 stddev:2.56 (1.6%) stddev-sans-first:2.69 05:45:00 INFO - PROCESS | 20735 | Values: 163.0 164.0 160.0 166.0 166.0 159.0 160.0 160.0 162.0 161.0 05:45:00 INFO - PROCESS | 20735 | 05:45:00 INFO - PROCESS | 20735 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.62 (2.6%) stddev-sans-first:1.36 05:45:00 INFO - PROCESS | 20735 | Values: 66.0 62.0 66.0 64.0 62.0 63.0 62.0 63.0 62.0 62.0 05:45:00 INFO - PROCESS | 20735 | -------- Summary: end -------- 05:45:00 INFO - PROCESS | 20735 | 05:45:01 INFO - TEST-INFO | 20735: exit 0 05:45:01 INFO - TEST-OK | kraken | took 61752ms 05:45:01 INFO - SUITE-END | took 1175s 05:45:01 INFO - Completed test suite (00:19:35) 05:45:01 INFO - javascript benchmark 05:45:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3059.1281484776837, 3060.724534791867, 3076.0618011506494, 3105.409972105301, 3112.393083348748, 5181.637285390024, 5213.139831927728, 5287.762050707718, 5345.599080068996, 5363.597730170238, 5290.68255904646, 5302.787888484456, 5304.655197412175, 5335.33308336458, 5404.135338345865, 4770.284457331393, 4842.539958703917, 4868.221084626461, 4869.318295438641, 4975.253711943201, 4874.960114869172, 4890.119778439897, 4891.4130304363525, 4941.802327906879, 4961.875953101166, 4762.64280178988, 4801.4718380978065, 4834.661573689843, 4835.492273311287, 4862.246351815475, 4854.0, 4873.098476781806, 4873.829415970424, 4929.630277729185, 4973.154563724175, 4892.877678058041, 4900.093482705711, 4907.705537667752, 4909.484504127051, 4913.385826771653, 5224.425313215544, 5259.158534634478, 5335.039692855285, 5339.225812257201, 5365.731713414352, 5729.570282228808, 5738.971305143489, 5751.05107657236, 5755.712214389264, 5755.884882302331, 5640.464155905182, 5743.798967036176, 5744.425557444264, 5784.1902133701315, 5796.82609521715, 5231.555317797992, 5285.841424757264, 5295.338082739658, 5369.812056578079, 5380.219868119101, 5898.616589921641, 5914.674692891932, 5917.0, 5917.822465326047, 5923.526117910556, 5460.726963651801, 5503.3120859892515, 5695.40198279183, 5699.202111704365, 5701.030824759718, 5601.691906945076, 5622.690752008679, 5641.238432811518, 5643.0, 5660.745266463019, 5653.462921022497, 5658.575606829504, 5659.434056594308, 5661.433856614306, 5664.97167514168, 5607.607467477237, 5609.1305847593685, 5664.830055098354, 5667.4332566743, 5670.744816483269, 4302.913941721211, 4348.304271316574, 4379.255526560429, 4390.363397307437, 4393.055493069006, 3904.9804750976064, 3911.6283953024417, 3914.178022615226, 3949.4273330366937, 3954.0114971257185, 5265.420803711589, 5398.2982212312145, 5407.215953686693, 5409.7295135243085, 5410.215518749759, 5599.412061733459, 5647.6893770843, 5658.179563963285, 5699.401562835926, 5706.971465142731, 5360.65155764874, 5390.622656414013, 5391.083515802323, 5394.406615272317, 5397.217403476552, 4875.878103047381, 4884.902301954012, 4931.5315045070665, 4931.630127740362, 4961.776720047606, 5781.682007489629, 5832.387599302097, 5853.853653658693, 5854.18041474194, 5898.764049437975, 5667.716614169358, 5682.886342273214, 5683.261176047253, 5684.431556844283, 5700.743466543932, 5745.597808153389, 5794.62334948227, 5797.4782269595935, 5833.883322333614, 5857.384974577779, 5628.943710562842, 5638.154276858504, 5639.3232812062815, 5682.403347648603, 5692.203091567101, 5679.460451257124, 5721.599488035798, 5728.1694154348215, 5734.168545560787, 5736.110902810071, 5401.756920938488, 5527.474889885454, 5555.972220138873, 5575.749091290821, 5586.02244607184, 5543.445655434585, 5584.636998595079, 5587.7485513152815, 5588.972055139699, 5628.549716022627, 5489.094299440548, 5495.725213739217, 5497.862553436036, 5498.285222920995, 5500.477454641803, 5416.24172615842, 5510.173473978935, 5574.191742197278, 5582.581306401955, 5649.0, 5500.944990550043, 5512.448755124456, 5517.5585953124455, 5519.089350257163, 5520.779168833202], "value": 5253.868200686713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.9578629495937, 635.5360586771657, 637.9649119298438, 641.7497176101324, 651.524387197346, 32039.199020024513, 44197.0, 44751.0, 44761.0, 44882.10235795282, 47304.0, 47913.281300780516, 47992.760036199856, 47997.28004079933, 48000.27999580009, 8889.955550222256, 9021.36850420471, 9030.86453703195, 9038.322125840556, 9038.502882341501, 8830.823383532357, 8928.598213080395, 8941.105889411072, 8941.374103812735, 8944.955275223614, 8954.417962832395, 9001.549922503882, 9011.774705632379, 9033.412828166181, 9038.683646072388, 8909.910900891005, 8949.910500895037, 8955.776105597348, 8958.910410895873, 8961.580373299204, 8910.599023043978, 8979.057198994142, 8985.0, 8991.100889911022, 8994.145556172152, 43945.78027109876, 44027.33958990618, 44310.11379772419, 44361.334579981325, 44366.556334436566, 3435.8282085895853, 3544.078539579728, 3647.0335361129323, 3651.543557055368, 3999.900002499961, 33522.6647733522, 33941.83029084864, 34073.659263407295, 34760.826195869115, 35222.64777352219, 4356.172327257811, 4379.868603941887, 4380.715253508543, 4430.11397720454, 4633.32816741574, 256350.0, 256627.0, 257011.0, 257616.0, 258218.9999999981, 60824.39175608276, 61325.693371532856, 61514.69242653758, 61592.384076159564, 61610.69194653998, 57558.424415755304, 57758.42241577614, 57978.0, 58333.0, 63604.36395636077, 16604.0, 17273.309067637405, 17273.481795546155, 18038.2784688611, 18051.909740451214, 13779.724405512034, 13943.930280348533, 13951.441942322193, 13959.651008724863, 13984.720305594035, 16764.58088547796, 16830.07434591085, 18146.81853181451, 18285.725714114298, 18459.815401845808, 6220.782272620527, 6397.584157029872, 6465.450436712838, 6522.380373864476, 6536.934630653727, 997.9501024948869, 1060.0088916862758, 1073.6043143912882, 1148.0987424871437, 1165.685264978468, 33050.83474582612, 33608.0, 33700.49449258263, 33773.83113084468, 34212.48681269783, 1104.8950349716763, 1106.2643342177523, 1109.5007246738999, 1134.2797323699344, 1143.6283207957447, 17344.219510122075, 17371.73942390865, 17400.0, 17533.91233043852, 17557.561060973323, 481315.0, 483046.0, 483248.0, 483553.0, 483827.0, 59537.0, 61139.69430152821, 61181.082283765776, 61218.0817287741, 61320.69339653362, 1357.3077730357588, 1368.5210176438168, 1396.1343966740483, 1397.594697537723, 1408.8661577150153, 31022.0, 32038.35923281568, 32175.51736723904, 32205.35589288248, 33085.50371744474, 53754.73122634362, 54035.189472157166, 54117.18824217719, 54209.72895135499, 54251.72874135604, 415669.0, 415711.0, 415982.0, 416803.0, 417347.0, 42172.0, 44263.0, 44895.32657010082, 45446.54553454423, 45570.0, 50808.23787643114, 50938.745306274715, 51053.0, 51517.742411287705, 51678.0, 41716.0, 42183.7890810544, 43078.0, 43261.78369108134, 43802.34296485619, 42083.57916420919, 42467.0, 43075.56924430715, 43822.56177438185, 43955.7033222516, 42277.0, 42806.35790463208, 43009.354859677755, 43660.34509482424, 43710.781446092566, 36089.45865811963, 37401.0, 37978.81417245075, 38454.2309153821, 38538.421923671725, 28664.283392915346, 28921.0, 29094.0, 29430.0, 29449.263768405963], "value": 20595.158593728826, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.9268146370723, 374.9137698329386, 381.5345278759913, 394.44383419378676, 409.83606557377044, 17143.399981000668, 18740.344087956924, 18832.717509237336, 18834.905825470854, 19316.51708707283, 17913.7312940306, 18919.0, 19179.0, 19904.402867913985, 19971.700424493607, 5282.5245727884485, 5296.285001524793, 5368.409474957752, 5477.643953143053, 5482.588805839558, 5306.389765176996, 5342.439043900393, 5343.893122137555, 5362.678239305635, 5381.246625472437, 5185.455527169649, 5299.336346642667, 5360.678359298455, 5363.141897296433, 5367.651102678333, 5345.679259244438, 5355.571554275649, 5360.222767698662, 5391.81128660495, 5400.972995135019, 5188.662736922088, 5236.136037553799, 5239.3712754469325, 5239.637694199498, 5259.053370393328, 13706.93146534271, 13891.305434728223, 13969.650758731113, 14121.788173177309, 19651.705224421647, 2266.614675505168, 2278.2254033628647, 2379.9881000594887, 2403.42317843718, 2493.2146373892165, 10077.0, 10157.984201579857, 10785.245032847704, 13088.0, 13405.932970335183, 1922.4424916774258, 1995.4412764425983, 2025.9696104558443, 2039.3168288623328, 2162.1459523488293, 75288.62355688187, 77935.0, 78175.0, 81148.0, 84303.57848210781, 10633.0, 10727.892721072845, 10988.560457581767, 11160.609378671708, 11309.0, 15145.697086058217, 15180.544583662508, 15242.847571524142, 15247.618809529851, 15264.465743698916, 8168.591570421455, 8252.381071419668, 8335.208155225244, 8456.238938495564, 8460.153984601491, 8085.636146373427, 8200.712975045844, 8207.671693132328, 8299.751007469786, 8342.499450033018, 4675.976620116878, 4773.832915847998, 4799.256115302133, 4822.0, 4879.414470263591, 1924.1437560285538, 1955.6773132433273, 1975.0914579293685, 1976.9110390032483, 1979.5644958109192, 408.2855003743449, 419.61185903039564, 423.38397631446173, 424.7854833309159, 851.8296340731879, 11591.536338546364, 11631.59289424866, 11812.468438920268, 11816.231944923553, 11996.58011969577, 442.63040361298675, 447.42505879944116, 448.4528874772772, 449.03681602961467, 463.35593525000274, 10592.311499752492, 10602.681919542427, 11005.17461190414, 11113.666590002313, 11418.885811141949, 464337.0, 468321.0, 469465.0, 469986.0, 486819.0, 74582.8812567812, 89657.55171224102, 90464.54767726119, 93672.0, 95315.52342238244, 1049.160671462842, 1065.099990508035, 1084.8698156221146, 1121.3720316622716, 1123.6179698902413, 17693.0, 17700.822991770434, 18037.819621803614, 18059.819401805813, 18141.365052223107, 39919.40120898247, 42733.704621490404, 43035.0, 44170.337444938996, 44459.777701111285, 60906.0, 61209.69395152996, 62112.68943655253, 62191.37808621856, 62640.68679656572, 8597.097304783158, 8664.653413863374, 8676.436031657922, 8741.737747867573, 8790.252828509392, 9975.850362244428, 12453.626391208278, 13236.602901912958, 13472.663183420493, 13687.384067717176, 11618.302901825917, 12105.939470302592, 12251.69370765738, 12374.505019799468, 12442.81335779946, 11336.6032188873, 11874.168808183344, 12003.75992480163, 12040.578579749666, 12340.321282329558, 11483.540658373571, 12328.32194229326, 12551.87448125507, 12583.496660133493, 12647.810282845581, 8519.23326900582, 8774.254188393803, 8906.198442140238, 8925.598348074222, 9233.769155771159, 8937.865932011155, 11568.363739994382, 12025.398730063636, 12059.879401206226, 12097.334646594521], "value": 8643.347458033544, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.6856754378953, 1448.4278709909584, 1516.0979217365666, 1602.9679406411872, 1772.592303770132, 8084.797880053002, 8458.32333413327, 8458.407911446202, 8740.4755714657, 8756.824863502727, 8427.70503032394, 8628.223459888608, 8642.308615310776, 8656.480611163319, 8669.046404895456, 1905.237904838062, 1955.1690531524116, 2106.6102770987377, 2145.8819764912923, 2170.8697478151284, 2195.802377785999, 2213.800757931786, 2215.4350640586626, 2218.0794970087395, 2218.1792736687366, 2128.968065479019, 2137.476318302018, 2166.880821554814, 2168.2627906511784, 2168.3711723600136, 1894.4127320530679, 2010.9195632174842, 2072.5544008038346, 2074.771775104737, 2101.3170719516143, 2218.8351115664245, 2223.6998005269243, 2240.798328150458, 2243.181238847809, 2244.012634440841, 8683.869741953877, 8737.956310218471, 8795.340349473816, 8804.779880502974, 8806.295496360275, 4812.759362031919, 4943.283223932545, 4952.554270115671, 4957.380327459067, 4965.081459929925, 3073.9538906916414, 3145.779795414322, 3165.0, 3211.775175737699, 3212.341469998668, 1018.221060888421, 1026.7843752811918, 1038.6624347087152, 1039.9376037437778, 1054.0724162736742, 11994.400279985966, 11995.760084798343, 11998.64004079879, 12005.0, 12009.819852702216, 4371.300591905344, 4413.624841888475, 4439.467263928284, 4517.706349087299, 4520.186366454004, 10052.648157314605, 10053.145482634058, 10071.496425178711, 10075.59697612102, 10101.040401161878, 4946.950530494721, 4982.551570358685, 4983.6013118950395, 4984.40187177541, 4990.251462280687, 4103.158852435244, 4427.8228870844805, 4521.819127234874, 4536.56902594253, 4567.269236922077, 3587.2466781976077, 3633.818309084535, 3670.8898733037518, 3676.28312479064, 3701.851925922987, 1138.7153211697075, 1138.9886101139048, 1141.7145713571608, 1144.3019757947645, 1150.7065698246893, 986.5807032011367, 1013.614826365991, 1022.3610243597751, 1031.2265800649513, 1039.9792004159874, 5838.737256823453, 5914.76340946366, 5973.731182096816, 6132.662703551258, 6375.266844312963, 925.0703043441298, 938.5260443476001, 969.539468752337, 974.4202199691173, 1000.4397537379091, 4001.7198796084576, 4194.3918131870705, 4235.089455767024, 4262.573742625775, 4303.33298338758, 10400.47997600117, 10775.245732798785, 10820.02619764225, 10893.618723344645, 10919.727006824734, 10692.0, 10709.785804284027, 10712.732181695364, 10717.732056698645, 10802.891971080346, 7650.196729343562, 8095.380923815261, 8226.958865205635, 8230.341572674171, 8236.052853921876, 6054.606450580698, 6218.53360997936, 6246.937530624636, 6353.0, 6363.077353783583, 9996.950015250168, 10091.091801737584, 10210.744731381776, 10244.641437549948, 10254.948725256327, 10198.133158681298, 10300.948495257475, 10540.10409115203, 10571.471426428494, 10572.788544228919, 7016.649167541705, 7611.0, 7777.611119444118, 7857.4499785014505, 7950.840983180188, 8609.569521524025, 8657.39398242117, 8675.089115643019, 8731.869021964803, 8740.388172827841, 7440.962795185989, 7628.580428076509, 7648.273414025659, 7700.768976930701, 7783.260590243918, 7621.657025433758, 7624.085109786861, 7696.307332339892, 7770.0, 7809.1409944907155, 7089.82275443118, 7140.642967851691, 7181.497295189287, 7192.604406757679, 7196.352328290479, 5242.039352448152, 5245.816396426259, 5266.736663166903, 5295.152775555893, 5463.125899856004, 5181.5077567631015, 5342.973285133549, 5356.866078348073, 5397.919031214614, 5517.144842549411], "value": 4533.3598640459295, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.44282256655947, 300.7383576288629, 300.9789314747968, 301.92451887028244, 308.8471206752657, 5395.59533035022, 5410.540104091147, 5487.780488780449, 5496.7526461309235, 5505.917411238835, 5115.44227886057, 5202.8179013734525, 5206.687598744069, 5247.396549396821, 5356.491133342326, 3998.5001874765653, 4054.209429161315, 4081.693872959525, 4114.732542384751, 4122.505299364066, 4121.690873184508, 4125.690573207022, 4127.979360103195, 4139.668826493873, 4156.688248381369, 4038.1519880825063, 4052.9594704053025, 4076.429299898017, 4100.487439070116, 4121.690873184508, 4056.424170651628, 4057.504634649699, 4062.2687916175078, 4106.917861642781, 4120.938185927213, 3598.9100272493133, 3617.0, 3621.529201203853, 3638.7991962652527, 3655.94516082259, 5120.180771076647, 5251.343582052244, 5276.94199262288, 5281.445448227958, 5306.363236411656, 4568.360429539867, 4620.567624036559, 4656.254999200146, 4710.905781884343, 4715.127701375222, 5554.19464177696, 5557.138643510262, 5569.0, 5578.183017576098, 5638.548916086703, 3838.520838111942, 3892.5718171001167, 3910.19840932611, 3927.8428862845453, 3968.384900340423, 5845.503132233765, 5873.676947767871, 5876.0, 5882.8822523720355, 5884.617499862496, 3974.0, 3974.20515896816, 3984.322665146932, 3986.5216174059296, 3994.7603143811466, 4054.351303791438, 4058.1072164123843, 4060.3503439450137, 4068.959310406917, 4072.7352722073565, 4511.293204116022, 4595.333676616939, 4637.373954516097, 4678.69588476748, 4694.436667599842, 5583.69289689071, 5594.790057927096, 5606.177992152485, 5624.803131890446, 5646.830595082154, 1337.732453509302, 1355.3629793997002, 1380.4616199682132, 1392.1438315435935, 1397.5457976157788, 849.9320054395633, 863.2101627011215, 892.8616064510011, 894.2622336572354, 900.594732370436, 4771.373755945437, 4855.0, 4879.463259041502, 4882.975585122052, 4889.8216159496615, 5134.358205224347, 5136.0, 5148.841510660146, 5158.200478925773, 5159.0, 4656.022235330618, 4781.780645935264, 4818.542238487338, 4821.300911367832, 4903.8528844134735, 2726.768224700883, 2733.4259805440684, 2733.9589906151423, 2738.6850512191354, 2745.697973222944, 5133.330668052592, 5202.66182698131, 5215.765290561933, 5237.450067742909, 5253.579713622901, 4789.664723469394, 5208.8437346879655, 5209.557187639018, 5224.622796627601, 5240.68555886648, 5124.385818467171, 5144.15121504955, 5185.818496352609, 5188.844334669966, 5242.423333433319, 5073.34046573943, 5091.8614182044375, 5105.7191854448365, 5124.538791508782, 5159.509846564571, 4684.016356565159, 4990.700557966534, 5062.670926389773, 5127.307813445286, 5160.277561141368, 5182.818601348935, 5244.977229440223, 5266.210068489604, 5343.385510666323, 5354.965113256937, 3605.5853576839, 3664.929314937159, 3708.9072773180887, 3712.276106159381, 3716.95925140961, 4919.729414882216, 5081.72050537224, 5127.20547808279, 5175.844724658266, 5554.694491802989, 5469.535089517276, 5570.5265052470995, 5603.646468483328, 5665.745041473061, 5702.0, 5648.915266271091, 5662.320521537442, 5667.829965101054, 5688.800891968761, 5695.943040569541, 5443.846000398931, 5468.72656367188, 5516.227728118147, 5596.2298662160865, 5602.719864006702, 5570.4151064139305, 5612.466815652507, 5664.348599911063, 5688.975984322861, 5724.656520608777, 5417.918731218956, 5567.137093750475, 5581.386047534849, 5581.413951535031, 5609.49514543693], "value": 4071.258098794328, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.390062099389, 759.8860170974353, 787.1341524323242, 793.2107552984781, 830.2154464031493, 15195.088294702326, 16176.676466470664, 16501.174941252935, 16517.83482165178, 16541.66916661666, 11705.70735731607, 14683.559493215194, 14839.887008474354, 14848.554543363716, 14859.405623775036, 799.0611032037361, 817.513579420247, 817.9100298967109, 823.567626995826, 828.2587084559316, 805.9677612895478, 809.8947136872198, 811.7524155132683, 811.9188081191893, 812.2364976921705, 711.4450728431832, 827.8882350882614, 834.2325060943946, 839.9622017009219, 840.9369297302696, 852.1010334097525, 852.1904191018496, 852.9317654587618, 853.1596377568121, 854.4275335525182, 613.4019331152135, 614.7571709174896, 619.1239396254294, 620.0234630457003, 622.3993845938691, 15225.771613425808, 15253.38986440541, 15296.770548441782, 15318.463853765064, 15456.690866182613, 14345.0, 14421.35103920326, 14576.635584110376, 14691.191984440851, 14696.868954001717, 13076.86923130766, 13244.668883277898, 13259.86740132596, 13343.73312533744, 13611.251381174032, 4477.306017567283, 4483.932741008887, 4490.775461226958, 4530.07133537624, 4575.49669536354, 78962.60518697427, 79622.60188699077, 79654.60172699158, 80065.19934800635, 80470.59764701198, 11197.888021119685, 11212.66362009141, 11437.714057148638, 11607.76784464306, 11873.287602743863, 21432.686386300906, 21714.56570868574, 21743.891280543812, 21795.128194872028, 22354.0, 8273.751787446266, 8285.917140828515, 8380.53907035119, 8474.322054235647, 8732.78168045804, 4589.495155532889, 4675.438947326343, 4691.390119284471, 4829.082474329866, 4837.250226214872, 3362.8486718097743, 3417.9316413671586, 3428.7257019438384, 3434.9828250858586, 3470.6876381125317, 590.2621722846442, 594.9553783466257, 601.6029420582395, 660.8182749743859, 663.3366633366634, 639.1626968671055, 689.7310049080862, 709.2234003765868, 710.2897102897103, 718.7448455798117, 10007.749806254757, 10293.228007899437, 10352.741181470523, 10448.947755261175, 10513.474326283655, 693.5006795107514, 712.4585315160515, 713.6289129652552, 768.9846203075907, 776.3711393771064, 4642.698224615389, 4650.767461626905, 4719.764011799396, 4847.95152048475, 4864.6594738368685, 124508.37745811213, 127411.36294318654, 127772.0, 128127.35936320259, 129857.0, 83399.0, 103519.4824025875, 103767.4811625937, 104032.0, 106329.46835265774, 71178.64410678016, 75212.62393687997, 94143.52928235452, 94243.52878235564, 97056.51471742596, 8889.19997200255, 9030.0, 9037.277017838689, 9151.496667683341, 9270.953645231732, 42818.5718142827, 51457.0, 53188.0, 54034.729826350616, 54725.0, 71301.0, 81945.59027205064, 82145.58927205326, 84464.0, 86354.1364586346, 11899.564954622267, 12280.75438491243, 12312.199707019015, 12377.876221238033, 13247.072704910564, 13999.650008749863, 14162.858371416567, 14366.281685915454, 14491.49279775245, 14508.419663213775, 9617.326787124834, 12773.552925647558, 12980.415881285177, 13867.653308667364, 13942.442302307794, 14420.639484012983, 14579.562613121623, 14663.780043299146, 14753.704925901207, 14783.482578110144, 14079.788803167756, 14218.928905355408, 14274.785878212042, 14504.927475362556, 14609.050411723203, 11252.887471125512, 11279.266847654877, 11641.243319184226, 11654.80249646081, 11837.289762614271, 11730.519383919655, 11780.52877884875, 11798.469068891749, 12149.1495595311, 12177.0], "value": 7690.347736188165, "unit": "score"}], "name": "dromaeo_css", "value": 7142.411459836511, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 110.0, 109.0, 110.0, 111.0, 111.0, 110.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 128.0, 181.0, 132.0, 129.0, 167.0, 126.0, 170.0, 134.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 119.0, 75.0, 76.0, 75.0, 76.0, 98.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 111.0, 85.0, 85.0, 85.0, 111.0, 85.0, 85.0, 133.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 149.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 55.0, 56.0, 56.0, 55.0, 56.0, 55.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 79.0, 84.0, 80.0, 80.0, 82.0, 79.0, 108.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 123.0, 129.0, 121.0, 122.0, 121.0, 137.0, 137.0, 135.0, 134.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 160.0, 166.0, 166.0, 159.0, 160.0, 160.0, 162.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 66.0, 64.0, 62.0, 63.0, 62.0, 63.0, 62.0, 62.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1507.3, "alertThreshold": 2.0}]} 05:45:01 INFO - Return code: 0 05:45:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:45:01 INFO - # TBPL SUCCESS # 05:45:01 INFO - Running post-action listener: _resource_record_post_action 05:45:01 INFO - ##### 05:45:01 INFO - ##### Finished run-tests step (success) 05:45:01 INFO - ##### 05:45:01 INFO - Running post-run listener: _resource_record_post_run 05:45:01 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 548847616; Read time: 0; Write time: 607384 05:45:01 INFO - TinderboxPrint: CPU usage
13.2% 05:45:01 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:45:01 INFO - TinderboxPrint: I/O write bytes / time
548,847,616 / 607,384 05:45:01 INFO - TinderboxPrint: CPU idle
8,265.1 (86.7%) 05:45:01 INFO - TinderboxPrint: CPU user
1,210.7 (12.7%) 05:45:01 INFO - TinderboxPrint: Swap in / out
0 / 0 05:45:01 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 219881472; Read time: 0; Write time: 306036 05:45:01 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 292892672; Read time: 0; Write time: 294720 05:45:02 INFO - Running post-run listener: _upload_blobber_files 05:45:02 INFO - Blob upload gear active. 05:45:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:45:02 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 05:45:02 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'] 05:45:02 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 05:45:02 INFO - (blobuploader) - INFO - Open directory for files ... 05:45:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:45:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:45:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:03 INFO - (blobuploader) - INFO - Done attempting. 05:45:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:45:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:07 INFO - (blobuploader) - INFO - Done attempting. 05:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:08 INFO - (blobuploader) - INFO - Done attempting. 05:45:08 INFO - (blobuploader) - INFO - Iteration through files over. 05:45:08 INFO - Return code: 0 05:45:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:45:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:45:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bc001b3fca918f1fa76bb3a401a30ad4cd98ae2484af6f8ea096a0012dbfbedbb371549e9e12124ae80aebc94fe714734741c6168a75a96a05e0604317df574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae32c081199f2999339b2f6e7f609bf21a14b60d594e71ecba13256d4daa8711c0a7d99eda075cd687befc243eb941fed1c6d0037b4f13f72e439a0b6095b57b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ca16dc4c16650563d3644826c7c63e3ac98fce58fae3612f3b7f4aef7202dcc5914075d5d6ce0a2dbd41f9e806decd0ef5bf66ae68dbdfc0eac15814d5c2cc8"} 05:45:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:45:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:45:08 INFO - Contents: 05:45:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bc001b3fca918f1fa76bb3a401a30ad4cd98ae2484af6f8ea096a0012dbfbedbb371549e9e12124ae80aebc94fe714734741c6168a75a96a05e0604317df574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae32c081199f2999339b2f6e7f609bf21a14b60d594e71ecba13256d4daa8711c0a7d99eda075cd687befc243eb941fed1c6d0037b4f13f72e439a0b6095b57b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ca16dc4c16650563d3644826c7c63e3ac98fce58fae3612f3b7f4aef7202dcc5914075d5d6ce0a2dbd41f9e806decd0ef5bf66ae68dbdfc0eac15814d5c2cc8"} 05:45:08 INFO - Running post-run listener: copy_logs_to_upload_dir 05:45:08 INFO - Copying logs to upload dir... 05:45:08 INFO - mkdir: /builds/slave/test/build/upload/logs 05:45:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.048868 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-06-17 05:45:08.411296) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 05:45:08.414906) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bc001b3fca918f1fa76bb3a401a30ad4cd98ae2484af6f8ea096a0012dbfbedbb371549e9e12124ae80aebc94fe714734741c6168a75a96a05e0604317df574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae32c081199f2999339b2f6e7f609bf21a14b60d594e71ecba13256d4daa8711c0a7d99eda075cd687befc243eb941fed1c6d0037b4f13f72e439a0b6095b57b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ca16dc4c16650563d3644826c7c63e3ac98fce58fae3612f3b7f4aef7202dcc5914075d5d6ce0a2dbd41f9e806decd0ef5bf66ae68dbdfc0eac15814d5c2cc8"} build_url:https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/FLzwKnF1RhimQR5WI8FYNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bc001b3fca918f1fa76bb3a401a30ad4cd98ae2484af6f8ea096a0012dbfbedbb371549e9e12124ae80aebc94fe714734741c6168a75a96a05e0604317df574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae32c081199f2999339b2f6e7f609bf21a14b60d594e71ecba13256d4daa8711c0a7d99eda075cd687befc243eb941fed1c6d0037b4f13f72e439a0b6095b57b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ca16dc4c16650563d3644826c7c63e3ac98fce58fae3612f3b7f4aef7202dcc5914075d5d6ce0a2dbd41f9e806decd0ef5bf66ae68dbdfc0eac15814d5c2cc8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 05:45:08.441455) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:45:08.441870) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1466166245.912119-1907463422 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004669 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 05:45:08.527023) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 05:45:08.527401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 05:45:08.527747) ========= ========= Total master_lag: 0.18 =========