builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1450271323.15 results: success (0) buildid: 20151216034107 builduid: 0f8a5ea6ac644dfeaaad8c231688e43b revision: a1393f2f306ef87ddaf36b8a91d8ac5aa1370134 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.150360) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.150861) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.151187) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.004358 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.168841) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.169192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.182551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.182957) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004630 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.233694) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.234068) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.234514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.234823) ========= 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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 05:08:44-- 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% 43.4M=0s 2015-12-16 05:08:44 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.239712 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.487482) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.487832) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020143 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.539068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:43.539473) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a1393f2f306ef87ddaf36b8a91d8ac5aa1370134 --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 a1393f2f306ef87ddaf36b8a91d8ac5aa1370134 --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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 05:08:44,995 truncating revision to first 12 chars 2015-12-16 05:08:44,995 Setting DEBUG logging. 2015-12-16 05:08:44,995 attempt 1/10 2015-12-16 05:08:44,995 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a1393f2f306e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 05:08:45,241 unpacking tar archive at: fx-team-a1393f2f306e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587142 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:44.140054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:44.140431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:44.149767) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:44.150151) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 05:08:44.150653) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-16 05:08:44.150973) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:08:45 INFO - MultiFileLogger online at 20151216 05:08:45 in /builds/slave/test 05:08:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:08:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:45 INFO - {'append_to_log': False, 05:08:45 INFO - 'base_work_dir': '/builds/slave/test', 05:08:45 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:08:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:45 INFO - 'branch': 'Fx-Team-Non-PGO', 05:08:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:45 INFO - 'config_files': ('talos/linux_config.py',), 05:08:45 INFO - 'default_actions': ('clobber', 05:08:45 INFO - 'read-buildbot-config', 05:08:45 INFO - 'download-and-extract', 05:08:45 INFO - 'populate-webroot', 05:08:45 INFO - 'create-virtualenv', 05:08:45 INFO - 'install', 05:08:45 INFO - 'run-tests'), 05:08:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:45 INFO - 'download_minidump_stackwalk': True, 05:08:45 INFO - 'download_symbols': 'ondemand', 05:08:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:45 INFO - 'installer_path': 'installer.exe', 05:08:45 INFO - 'log_level': 'info', 05:08:45 INFO - 'log_name': 'talos', 05:08:45 INFO - 'log_to_console': True, 05:08:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:08:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:08:45 INFO - 'opt_config_files': (), 05:08:45 INFO - 'pip_index': False, 05:08:45 INFO - 'sps_profile': False, 05:08:45 INFO - 'sps_profile_interval': 0, 05:08:45 INFO - 'suite': 'dromaeojs-e10s', 05:08:45 INFO - 'system_bits': '64', 05:08:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:45 INFO - 'title': 'talos-linux64-ix-044', 05:08:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:45 INFO - 'use_talos_json': True, 05:08:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:45 INFO - 'work_dir': 'build'} 05:08:45 INFO - ##### 05:08:45 INFO - ##### Running clobber step. 05:08:45 INFO - ##### 05:08:45 INFO - Running pre-action listener: _resource_record_pre_action 05:08:45 INFO - Running main action method: clobber 05:08:45 INFO - rmtree: /builds/slave/test/build 05:08:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:46 INFO - Running post-action listener: _resource_record_post_action 05:08:46 INFO - ##### 05:08:46 INFO - ##### Running read-buildbot-config step. 05:08:46 INFO - ##### 05:08:46 INFO - Running pre-action listener: _resource_record_pre_action 05:08:46 INFO - Running main action method: read_buildbot_config 05:08:46 INFO - Using buildbot properties: 05:08:46 INFO - { 05:08:46 INFO - "properties": { 05:08:46 INFO - "buildnumber": 584, 05:08:46 INFO - "product": "firefox", 05:08:46 INFO - "script_repo_revision": "production", 05:08:46 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:08:46 INFO - "repository": "", 05:08:46 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 05:08:46 INFO - "buildid": "20151216034107", 05:08:46 INFO - "slavename": "talos-linux64-ix-044", 05:08:46 INFO - "pgo_build": "False", 05:08:46 INFO - "basedir": "/builds/slave/test", 05:08:46 INFO - "project": "", 05:08:46 INFO - "platform": "ubuntu64_hw", 05:08:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:08:46 INFO - "slavebuilddir": "test", 05:08:46 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:08:46 INFO - "branch": "fx-team", 05:08:46 INFO - "repo_path": "integration/fx-team", 05:08:46 INFO - "stage_platform": "linux64", 05:08:46 INFO - "builduid": "0f8a5ea6ac644dfeaaad8c231688e43b", 05:08:46 INFO - "revision": "a1393f2f306ef87ddaf36b8a91d8ac5aa1370134" 05:08:46 INFO - }, 05:08:46 INFO - "sourcestamp": { 05:08:46 INFO - "repository": "", 05:08:46 INFO - "hasPatch": false, 05:08:46 INFO - "project": "", 05:08:46 INFO - "branch": "fx-team-linux64-talos", 05:08:46 INFO - "changes": [ 05:08:46 INFO - { 05:08:46 INFO - "category": null, 05:08:46 INFO - "files": [ 05:08:46 INFO - { 05:08:46 INFO - "url": null, 05:08:46 INFO - "name": "https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 05:08:46 INFO - } 05:08:46 INFO - ], 05:08:46 INFO - "repository": "", 05:08:46 INFO - "rev": "a1393f2f306ef87ddaf36b8a91d8ac5aa1370134", 05:08:46 INFO - "who": "cbook@mozilla.com", 05:08:46 INFO - "when": 1450269399, 05:08:46 INFO - "number": 7365806, 05:08:46 INFO - "comments": "Bug 1209344 - link to about:debugging from about:addons r=mossop", 05:08:46 INFO - "project": "", 05:08:46 INFO - "at": "Wed 16 Dec 2015 04:36:39", 05:08:46 INFO - "branch": "fx-team-linux64-talos", 05:08:46 INFO - "revlink": "", 05:08:46 INFO - "properties": [ 05:08:46 INFO - [ 05:08:46 INFO - "buildid", 05:08:46 INFO - "20151216034107", 05:08:46 INFO - "Change" 05:08:46 INFO - ], 05:08:46 INFO - [ 05:08:46 INFO - "builduid", 05:08:46 INFO - "0f8a5ea6ac644dfeaaad8c231688e43b", 05:08:46 INFO - "Change" 05:08:46 INFO - ], 05:08:46 INFO - [ 05:08:46 INFO - "pgo_build", 05:08:46 INFO - "False", 05:08:46 INFO - "Change" 05:08:46 INFO - ] 05:08:46 INFO - ], 05:08:46 INFO - "revision": "a1393f2f306ef87ddaf36b8a91d8ac5aa1370134" 05:08:46 INFO - } 05:08:46 INFO - ], 05:08:46 INFO - "revision": "a1393f2f306ef87ddaf36b8a91d8ac5aa1370134" 05:08:46 INFO - } 05:08:46 INFO - } 05:08:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:08:46 INFO - Running post-action listener: _resource_record_post_action 05:08:46 INFO - ##### 05:08:46 INFO - ##### Running download-and-extract step. 05:08:46 INFO - ##### 05:08:46 INFO - Running pre-action listener: _resource_record_pre_action 05:08:46 INFO - Running main action method: download_and_extract 05:08:46 INFO - mkdir: /builds/slave/test/build/tests 05:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:46 INFO - https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/test_packages.json 05:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/test_packages.json 05:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:08:46 INFO - Downloaded 1302 bytes. 05:08:46 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:08:46 INFO - Using the following test package requirements: 05:08:46 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:46 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:08:46 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'jsshell-linux-x86_64.zip'], 05:08:46 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:08:46 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:46 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:08:46 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:08:46 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:08:46 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:46 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:08:46 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:46 INFO - https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:08:47 INFO - Downloaded 21869080 bytes. 05:08:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 05:08:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:08:48 INFO - Return code: 0 05:08:48 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:08: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:08:48 INFO - https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:08:50 INFO - Downloaded 21869080 bytes. 05:08:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 05:08:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:08:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:08:51 INFO - Return code: 0 05:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:51 INFO - https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:08:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:08:52 INFO - Downloaded 11132052 bytes. 05:08:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 05:08:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:08:52 INFO - Return code: 0 05:08:52 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:08:52 INFO - https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:08:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:08:54 INFO - Downloaded 57741860 bytes. 05:08:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:54 INFO - mkdir: /builds/slave/test/properties 05:08:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:54 INFO - Contents: 05:08:54 INFO - build_url:https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - Running post-action listener: set_extra_try_arguments 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running populate-webroot step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: populate_webroot 05:08:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:54 INFO - '--tppagecycles', 05:08:54 INFO - '7'], 05:08:54 INFO - 'tests': ['tsvgm']}}, 05:08:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:08:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:08:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:08:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'tests': ['damp', 'tps']}, 05:08:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['damp', 'tps']}, 05:08:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:08:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:54 INFO - 'other': {'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other_l64': {'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'other_nol64': {'tests': ['a11yr', 05:08:54 INFO - 'ts_paint', 05:08:54 INFO - 'tpaint', 05:08:54 INFO - 'sessionrestore', 05:08:54 INFO - 'sessionrestore_no_auto_restore']}, 05:08:54 INFO - 'svgr': {'tests': ['tsvgx', 05:08:54 INFO - 'tsvgr_opacity', 05:08:54 INFO - 'tart', 05:08:54 INFO - 'tscrollx', 05:08:54 INFO - 'cart']}, 05:08:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tsvgx', 05:08:54 INFO - 'tsvgr_opacity', 05:08:54 INFO - 'tart', 05:08:54 INFO - 'tscrollx', 05:08:54 INFO - 'cart']}, 05:08:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'tests': ['tp5o']}, 05:08:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s'], 05:08:54 INFO - 'tests': ['tp5o']}, 05:08:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--xperf_path', 05:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:54 INFO - 'tests': ['tp5n']}, 05:08:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:54 INFO - 'talos_options': ['--e10s', 05:08:54 INFO - '--xperf_path', 05:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:54 INFO - 'tests': ['tp5n']}}, 05:08:54 INFO - 'talos.zip': {'path': '', 05:08:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running create-virtualenv step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: create_virtualenv 05:08:54 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:08:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:54 INFO - Using real prefix '/usr' 05:08:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:54 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:56 INFO - Installing pip.................done. 05:08:56 INFO - Return code: 0 05:08:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08: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:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:08:56 INFO - Using env: {'DISPLAY': ':0', 05:08:56 INFO - 'HOME': '/home/cltbld', 05:08:56 INFO - 'LANG': 'en_US.UTF-8', 05:08:56 INFO - 'LANGUAGE': 'en_US:en', 05:08:56 INFO - 'LOGNAME': 'cltbld', 05:08:56 INFO - 'MAIL': '/var/mail/cltbld', 05:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:56 INFO - 'MOZ_NO_REMOTE': '1', 05:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:56 INFO - 'NO_EM_RESTART': '1', 05:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:56 INFO - 'PWD': '/builds/slave/test', 05:08:56 INFO - 'SHELL': '/bin/bash', 05:08:56 INFO - 'SHLVL': '1', 05:08:56 INFO - 'TERM': 'linux', 05:08:56 INFO - 'TMOUT': '86400', 05:08:56 INFO - 'USER': 'cltbld', 05:08:56 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:56 INFO - '_': '/tools/buildbot/bin/python'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 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:08:57 INFO - Cleaning up... 05:08:57 INFO - Return code: 0 05:08:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 05:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 05:08:57 INFO - Using env: {'DISPLAY': ':0', 05:08:57 INFO - 'HOME': '/home/cltbld', 05:08:57 INFO - 'LANG': 'en_US.UTF-8', 05:08:57 INFO - 'LANGUAGE': 'en_US:en', 05:08:57 INFO - 'LOGNAME': 'cltbld', 05:08:57 INFO - 'MAIL': '/var/mail/cltbld', 05:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:57 INFO - 'MOZ_NO_REMOTE': '1', 05:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:57 INFO - 'NO_EM_RESTART': '1', 05:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:57 INFO - 'PWD': '/builds/slave/test', 05:08:57 INFO - 'SHELL': '/bin/bash', 05:08:57 INFO - 'SHLVL': '1', 05:08:57 INFO - 'TERM': 'linux', 05:08:57 INFO - 'TMOUT': '86400', 05:08:57 INFO - 'USER': 'cltbld', 05:08:57 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:57 INFO - '_': '/tools/buildbot/bin/python'} 05:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:57 INFO - Downloading/unpacking psutil>=0.7.1 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:58 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:08:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:59 INFO - Installing collected packages: psutil 05:08:59 INFO - Running setup.py install for psutil 05:08:59 INFO - building 'psutil._psutil_linux' extension 05:08:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:08:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:08:59 INFO - building 'psutil._psutil_posix' extension 05:08:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:08:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:08:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:59 INFO - Successfully installed psutil 05:08:59 INFO - Cleaning up... 05:08:59 INFO - Return code: 0 05:08:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 05:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 05:08:59 INFO - Using env: {'DISPLAY': ':0', 05:08:59 INFO - 'HOME': '/home/cltbld', 05:08:59 INFO - 'LANG': 'en_US.UTF-8', 05:08:59 INFO - 'LANGUAGE': 'en_US:en', 05:08:59 INFO - 'LOGNAME': 'cltbld', 05:08:59 INFO - 'MAIL': '/var/mail/cltbld', 05:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:59 INFO - 'MOZ_NO_REMOTE': '1', 05:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:59 INFO - 'NO_EM_RESTART': '1', 05:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:59 INFO - 'PWD': '/builds/slave/test', 05:08:59 INFO - 'SHELL': '/bin/bash', 05:08:59 INFO - 'SHLVL': '1', 05:08:59 INFO - 'TERM': 'linux', 05:08:59 INFO - 'TMOUT': '86400', 05:08:59 INFO - 'USER': 'cltbld', 05:08:59 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:59 INFO - '_': '/tools/buildbot/bin/python'} 05:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:01 INFO - Installing collected packages: mozsystemmonitor 05:09:01 INFO - Running setup.py install for mozsystemmonitor 05:09:01 INFO - Successfully installed mozsystemmonitor 05:09:01 INFO - Cleaning up... 05:09:01 INFO - Return code: 0 05:09:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:09:01 INFO - Using env: {'DISPLAY': ':0', 05:09:01 INFO - 'HOME': '/home/cltbld', 05:09:01 INFO - 'LANG': 'en_US.UTF-8', 05:09:01 INFO - 'LANGUAGE': 'en_US:en', 05:09:01 INFO - 'LOGNAME': 'cltbld', 05:09:01 INFO - 'MAIL': '/var/mail/cltbld', 05:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:01 INFO - 'MOZ_NO_REMOTE': '1', 05:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:01 INFO - 'NO_EM_RESTART': '1', 05:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:01 INFO - 'PWD': '/builds/slave/test', 05:09:01 INFO - 'SHELL': '/bin/bash', 05:09:01 INFO - 'SHLVL': '1', 05:09:01 INFO - 'TERM': 'linux', 05:09:01 INFO - 'TMOUT': '86400', 05:09:01 INFO - 'USER': 'cltbld', 05:09:01 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:01 INFO - '_': '/tools/buildbot/bin/python'} 05:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:09: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:09: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:09: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:09: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:09: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:09: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:09:03 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:03 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:09:03 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:09:03 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:09:03 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:09:03 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:09:03 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:09:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09: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:09: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:09: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:09: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:09: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:09: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:09:04 INFO - Downloading docopt-0.6.1.tar.gz 05:09:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:04 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:04 INFO - Running setup.py install for blobuploader 05:09:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:04 INFO - Running setup.py install for requests 05:09:04 INFO - Running setup.py install for docopt 05:09:04 INFO - Successfully installed blobuploader requests docopt 05:09:04 INFO - Cleaning up... 05:09:04 INFO - Return code: 0 05:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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:09:05 INFO - Using env: {'DISPLAY': ':0', 05:09:05 INFO - 'HOME': '/home/cltbld', 05:09:05 INFO - 'LANG': 'en_US.UTF-8', 05:09:05 INFO - 'LANGUAGE': 'en_US:en', 05:09:05 INFO - 'LOGNAME': 'cltbld', 05:09:05 INFO - 'MAIL': '/var/mail/cltbld', 05:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:05 INFO - 'MOZ_NO_REMOTE': '1', 05:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:05 INFO - 'NO_EM_RESTART': '1', 05:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:05 INFO - 'PWD': '/builds/slave/test', 05:09:05 INFO - 'SHELL': '/bin/bash', 05:09:05 INFO - 'SHLVL': '1', 05:09:05 INFO - 'TERM': 'linux', 05:09:05 INFO - 'TMOUT': '86400', 05:09:05 INFO - 'USER': 'cltbld', 05:09:05 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:05 INFO - '_': '/tools/buildbot/bin/python'} 05:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:05 INFO - Running setup.py (path:/tmp/pip-pBRxU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:05 INFO - Running setup.py (path:/tmp/pip-BLW3w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:05 INFO - Running setup.py (path:/tmp/pip-h_u8pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:05 INFO - Running setup.py (path:/tmp/pip-hYQeSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:05 INFO - Running setup.py (path:/tmp/pip-88P7cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:05 INFO - Running setup.py (path:/tmp/pip-07tWSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:05 INFO - Running setup.py (path:/tmp/pip-lAawHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:05 INFO - Running setup.py (path:/tmp/pip-5gzss0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:05 INFO - Running setup.py (path:/tmp/pip-tk3x6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:05 INFO - Running setup.py (path:/tmp/pip-sGCLTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:05 INFO - Running setup.py (path:/tmp/pip-OSUtXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:05 INFO - Running setup.py (path:/tmp/pip-li5VOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:06 INFO - Running setup.py (path:/tmp/pip-CMtXih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:06 INFO - Running setup.py (path:/tmp/pip-tssObQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:06 INFO - Running setup.py (path:/tmp/pip-QWldbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:06 INFO - Running setup.py (path:/tmp/pip-406uTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:06 INFO - Running setup.py (path:/tmp/pip-tiuy59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:06 INFO - Running setup.py install for manifestparser 05:09:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Running setup.py install for mozcrash 05:09:06 INFO - Running setup.py install for mozdebug 05:09:06 INFO - Running setup.py install for mozdevice 05:09:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:06 INFO - Running setup.py install for mozfile 05:09:06 INFO - Running setup.py install for mozhttpd 05:09:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for mozinfo 05:09:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for mozInstall 05:09:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for mozleak 05:09:07 INFO - Running setup.py install for mozlog 05:09:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for moznetwork 05:09:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for mozprocess 05:09:07 INFO - Running setup.py install for mozprofile 05:09:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for mozrunner 05:09:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:07 INFO - Running setup.py install for mozscreenshot 05:09:08 INFO - Running setup.py install for moztest 05:09:08 INFO - Running setup.py install for mozversion 05:09:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:08 INFO - Cleaning up... 05:09:08 INFO - Return code: 0 05:09:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:08 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:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:08 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:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:09:08 INFO - Using env: {'DISPLAY': ':0', 05:09:08 INFO - 'HOME': '/home/cltbld', 05:09:08 INFO - 'LANG': 'en_US.UTF-8', 05:09:08 INFO - 'LANGUAGE': 'en_US:en', 05:09:08 INFO - 'LOGNAME': 'cltbld', 05:09:08 INFO - 'MAIL': '/var/mail/cltbld', 05:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:08 INFO - 'MOZ_NO_REMOTE': '1', 05:09:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:08 INFO - 'NO_EM_RESTART': '1', 05:09:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:08 INFO - 'PWD': '/builds/slave/test', 05:09:08 INFO - 'SHELL': '/bin/bash', 05:09:08 INFO - 'SHLVL': '1', 05:09:08 INFO - 'TERM': 'linux', 05:09:08 INFO - 'TMOUT': '86400', 05:09:08 INFO - 'USER': 'cltbld', 05:09:08 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:08 INFO - '_': '/tools/buildbot/bin/python'} 05:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:08 INFO - Running setup.py (path:/tmp/pip-BRWqNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:08 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:08 INFO - Running setup.py (path:/tmp/pip-LV4MKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:08 INFO - Running setup.py (path:/tmp/pip-LCejVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:08 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:08 INFO - Running setup.py (path:/tmp/pip-J4opLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:08 INFO - Running setup.py (path:/tmp/pip-DHPLhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:08 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:08 INFO - Running setup.py (path:/tmp/pip-qeWw56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:08 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:08 INFO - Running setup.py (path:/tmp/pip-a5ehL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:08 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:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:08 INFO - Running setup.py (path:/tmp/pip-tiZe2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:09 INFO - Running setup.py (path:/tmp/pip-Eq7er9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:09 INFO - Running setup.py (path:/tmp/pip-pcFt1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:09 INFO - Running setup.py (path:/tmp/pip-GXXiCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:09 INFO - Running setup.py (path:/tmp/pip-85cnHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:09 INFO - Running setup.py (path:/tmp/pip-j9GUCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:09 INFO - Running setup.py (path:/tmp/pip-daxLm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:09 INFO - Running setup.py (path:/tmp/pip-skONBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:09 INFO - Running setup.py (path:/tmp/pip-ozORxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:09 INFO - Running setup.py (path:/tmp/pip-3hqerK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:09 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:09:09 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:09 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:09 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:09:09 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:09 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:11 INFO - Downloading blessings-1.5.1.tar.gz 05:09:11 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:11 INFO - Installing collected packages: blessings 05:09:11 INFO - Running setup.py install for blessings 05:09:11 INFO - Successfully installed blessings 05:09:11 INFO - Cleaning up... 05:09:11 INFO - Return code: 0 05:09:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:11 INFO - Reading from file tmpfile_stdout 05:09:11 INFO - Current package versions: 05:09:11 INFO - argparse == 1.2.1 05:09:11 INFO - blessings == 1.5.1 05:09:11 INFO - blobuploader == 1.2.4 05:09:11 INFO - docopt == 0.6.1 05:09:11 INFO - manifestparser == 1.1 05:09:11 INFO - mozInstall == 1.12 05:09:11 INFO - mozcrash == 0.16 05:09:11 INFO - mozdebug == 0.1 05:09:11 INFO - mozdevice == 0.47 05:09:11 INFO - mozfile == 1.2 05:09:11 INFO - mozhttpd == 0.7 05:09:11 INFO - mozinfo == 0.9 05:09:11 INFO - mozleak == 0.1 05:09:11 INFO - mozlog == 3.1 05:09:11 INFO - moznetwork == 0.27 05:09:11 INFO - mozprocess == 0.22 05:09:11 INFO - mozprofile == 0.27 05:09:11 INFO - mozrunner == 6.11 05:09:11 INFO - mozscreenshot == 0.1 05:09:11 INFO - mozsystemmonitor == 0.0 05:09:11 INFO - moztest == 0.7 05:09:11 INFO - mozversion == 1.4 05:09:11 INFO - psutil == 3.1.1 05:09:11 INFO - requests == 1.2.3 05:09:11 INFO - wsgiref == 0.1.2 05:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:09:11 INFO - Using env: {'DISPLAY': ':0', 05:09:11 INFO - 'HOME': '/home/cltbld', 05:09:11 INFO - 'LANG': 'en_US.UTF-8', 05:09:11 INFO - 'LANGUAGE': 'en_US:en', 05:09:11 INFO - 'LOGNAME': 'cltbld', 05:09:11 INFO - 'MAIL': '/var/mail/cltbld', 05:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:11 INFO - 'MOZ_NO_REMOTE': '1', 05:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:11 INFO - 'NO_EM_RESTART': '1', 05:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:11 INFO - 'PWD': '/builds/slave/test', 05:09:11 INFO - 'SHELL': '/bin/bash', 05:09:11 INFO - 'SHLVL': '1', 05:09:11 INFO - 'TERM': 'linux', 05:09:11 INFO - 'TMOUT': '86400', 05:09:11 INFO - 'USER': 'cltbld', 05:09:11 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:11 INFO - '_': '/tools/buildbot/bin/python'} 05:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:12 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 1)) 05:09:12 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 2)) 05:09:12 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 3)) 05:09:12 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 4)) 05:09:12 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 5)) 05:09:12 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 6)) 05:09:12 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 7)) 05:09:12 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 8)) 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:12 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 3)) 05:09:12 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:12 INFO - Cleaning up... 05:09:12 INFO - Return code: 0 05:09:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:12 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:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 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:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:09:12 INFO - Using env: {'DISPLAY': ':0', 05:09:12 INFO - 'HOME': '/home/cltbld', 05:09:12 INFO - 'LANG': 'en_US.UTF-8', 05:09:12 INFO - 'LANGUAGE': 'en_US:en', 05:09:12 INFO - 'LOGNAME': 'cltbld', 05:09:12 INFO - 'MAIL': '/var/mail/cltbld', 05:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:12 INFO - 'MOZ_NO_REMOTE': '1', 05:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:12 INFO - 'NO_EM_RESTART': '1', 05:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:12 INFO - 'PWD': '/builds/slave/test', 05:09:12 INFO - 'SHELL': '/bin/bash', 05:09:12 INFO - 'SHLVL': '1', 05:09:12 INFO - 'TERM': 'linux', 05:09:12 INFO - 'TMOUT': '86400', 05:09:12 INFO - 'USER': 'cltbld', 05:09:12 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:12 INFO - '_': '/tools/buildbot/bin/python'} 05:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:12 INFO - Downloading/unpacking jsonschema 05:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:09:13 INFO - Downloading/unpacking functools32 (from jsonschema) 05:09:13 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:09:13 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:09:13 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:09:13 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:09:13 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:09:13 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:09:14 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:14 INFO - warning: no files found matching '*.txt' 05:09:14 INFO - no previously-included directories found matching 'build' 05:09:14 INFO - no previously-included directories found matching 'dist' 05:09:14 INFO - no previously-included directories found matching '.git*' 05:09:14 INFO - Installing collected packages: jsonschema, functools32 05:09:14 INFO - Running setup.py install for functools32 05:09:14 INFO - warning: no files found matching '*.txt' 05:09:14 INFO - no previously-included directories found matching 'build' 05:09:14 INFO - no previously-included directories found matching 'dist' 05:09:14 INFO - no previously-included directories found matching '.git*' 05:09:14 INFO - Successfully installed jsonschema functools32 05:09:14 INFO - Cleaning up... 05:09:14 INFO - Return code: 0 05:09:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f54ae60df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x131f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ed240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, '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': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', '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:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:09:14 INFO - Using env: {'DISPLAY': ':0', 05:09:14 INFO - 'HOME': '/home/cltbld', 05:09:14 INFO - 'LANG': 'en_US.UTF-8', 05:09:14 INFO - 'LANGUAGE': 'en_US:en', 05:09:14 INFO - 'LOGNAME': 'cltbld', 05:09:14 INFO - 'MAIL': '/var/mail/cltbld', 05:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:14 INFO - 'MOZ_NO_REMOTE': '1', 05:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:14 INFO - 'NO_EM_RESTART': '1', 05:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:14 INFO - 'PWD': '/builds/slave/test', 05:09:14 INFO - 'SHELL': '/bin/bash', 05:09:14 INFO - 'SHLVL': '1', 05:09:14 INFO - 'TERM': 'linux', 05:09:14 INFO - 'TMOUT': '86400', 05:09:14 INFO - 'USER': 'cltbld', 05:09:14 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:14 INFO - '_': '/tools/buildbot/bin/python'} 05:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:14 INFO - Downloading/unpacking flake8 05:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:09:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:09:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:09:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:16 INFO - no previously-included directories found matching 'docs/_build' 05:09:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:17 INFO - Running setup.py install for pep8 05:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:17 INFO - no previously-included directories found matching 'docs/_build' 05:09:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:17 INFO - Cleaning up... 05:09:17 INFO - Return code: 0 05:09:17 INFO - Running post-action listener: _resource_record_post_action 05:09:17 INFO - Running post-action listener: _start_resource_monitoring 05:09:17 INFO - Starting resource monitoring. 05:09:17 INFO - ##### 05:09:17 INFO - ##### Running install step. 05:09:17 INFO - ##### 05:09:17 INFO - Running pre-action listener: _resource_record_pre_action 05:09:17 INFO - Running main action method: install 05:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:17 INFO - Reading from file tmpfile_stdout 05:09:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:17 INFO - Reading from file tmpfile_stdout 05:09:17 INFO - Output received: 05:09:17 INFO - Usage: mozinstall [options] installer 05:09:17 INFO - Options: 05:09:17 INFO - -h, --help show this help message and exit 05:09:17 INFO - -d DEST, --destination=DEST 05:09:17 INFO - Directory to install application into. [default: 05:09:17 INFO - "/builds/slave/test"] 05:09:17 INFO - --app=APP Application being installed. [default: firefox] 05:09:17 INFO - mkdir: /builds/slave/test/build/application 05:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:09:32 INFO - Reading from file tmpfile_stdout 05:09:32 INFO - Output received: 05:09:32 INFO - /builds/slave/test/build/application/firefox/firefox 05:09:32 INFO - Running post-action listener: _resource_record_post_action 05:09:32 INFO - ##### 05:09:32 INFO - ##### Running run-tests step. 05:09:32 INFO - ##### 05:09:32 INFO - Running pre-action listener: _resource_record_pre_action 05:09:32 INFO - Running main action method: run_tests 05:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:32 INFO - Python 2.7.3 05:09:32 INFO - Return code: 0 05:09:32 INFO - grabbing minidump binary from tooltool 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147c0b0>, '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 0x147cfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x147d440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:09:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:09:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:32 INFO - Return code: 0 05:09:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:09:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:09:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:09:33 INFO - Return code: 0 05:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] in /builds/slave/test/build 05:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost 05:09:33 INFO - Using env: {'DISPLAY': ':0', 05:09:33 INFO - 'HOME': '/home/cltbld', 05:09:33 INFO - 'LANG': 'en_US.UTF-8', 05:09:33 INFO - 'LANGUAGE': 'en_US:en', 05:09:33 INFO - 'LOGNAME': 'cltbld', 05:09:33 INFO - 'MAIL': '/var/mail/cltbld', 05:09:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:33 INFO - 'MOZ_NO_REMOTE': '1', 05:09:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:33 INFO - 'NO_EM_RESTART': '1', 05:09:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:33 INFO - 'PWD': '/builds/slave/test', 05:09:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:33 INFO - 'SHELL': '/bin/bash', 05:09:33 INFO - 'SHLVL': '1', 05:09:33 INFO - 'TERM': 'linux', 05:09:33 INFO - 'TMOUT': '86400', 05:09:33 INFO - 'USER': 'cltbld', 05:09:33 INFO - 'XDG_SESSION_COOKIE': '0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473', 05:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:33 INFO - '_': '/tools/buildbot/bin/python'} 05:09:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] with output_timeout 3600 05:09:33 INFO - mozversion INFO | application_buildid: 20151216034107 05:09:33 INFO - mozversion INFO | application_changeset: a1393f2f306ef87ddaf36b8a91d8ac5aa1370134 05:09:33 INFO - mozversion INFO | application_display_name: Nightly 05:09:33 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:33 INFO - mozversion INFO | application_name: Firefox 05:09:33 INFO - mozversion INFO | application_remotingname: firefox 05:09:33 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 05:09:33 INFO - mozversion INFO | application_vendor: Mozilla 05:09:33 INFO - mozversion INFO | application_version: 46.0a1 05:09:33 INFO - mozversion INFO | platform_buildid: 20151216034107 05:09:33 INFO - mozversion INFO | platform_changeset: a1393f2f306ef87ddaf36b8a91d8ac5aa1370134 05:09:33 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 05:09:33 INFO - mozversion INFO | platform_version: 46.0a1 05:09:33 INFO - 2015-12-16 05:09:33,415 DEBUG : using testdate: 1450271373 05:09:33 INFO - 2015-12-16 05:09:33,415 DEBUG : actual date: 1450271373 05:09:33 INFO - 2015-12-16 05:09:33,423 INFO : starting webserver on 'localhost:33667' 05:09:33 INFO - 2015-12-16 05:09:33,424 INFO : Starting test suite talos-linux64-ix-044.e 05:09:33 INFO - 2015-12-16 05:09:33,424 INFO : Starting test dromaeo_css 05:09:33 INFO - 2015-12-16 05:09:33,424 DEBUG : operating with platform_type : linux_ 05:09:33 INFO - 2015-12-16 05:09:33,425 INFO : Initialising browser for dromaeo_css test... 05:09:33 INFO - 2015-12-16 05:09:33,441 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO1WPeS/profile http://localhost:33667/getInfo.html 05:09:38 INFO - 2015-12-16 05:09:38,674 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:09:38 INFO - 2015-12-16 05:09:38,674 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:09:38 INFO - 2015-12-16 05:09:38,674 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:09:38 INFO - 2015-12-16 05:09:38,674 DEBUG : BROWSER_OUTPUT: __metrics 05:09:38 INFO - 2015-12-16 05:09:38,819 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:09:39 INFO - 2015-12-16 05:09:39,775 INFO : Browser initialized. 05:09:39 INFO - 2015-12-16 05:09:39,776 INFO : Running cycle 1/1 for dromaeo_css test... 05:09:39 INFO - 2015-12-16 05:09:39,776 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO1WPeS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:09:51 INFO - 2015-12-16 05:09:51,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 142950400 05:09:51 INFO - 2015-12-16 05:09:51,784 DEBUG : BROWSER_OUTPUT: 05:10:41 INFO - 2015-12-16 05:10:41,396 DEBUG : BROWSER_OUTPUT: 1450271441392 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:12:53 INFO - 2015-12-16 05:12:53,284 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33667/tests/dromaeo/cssquery-ext.html) 05:12:54 INFO - 2015-12-16 05:12:54,252 DEBUG : BROWSER_OUTPUT: RSS: Main: 146280448 05:12:54 INFO - 2015-12-16 05:12:54,253 DEBUG : BROWSER_OUTPUT: 05:15:56 INFO - 2015-12-16 05:15:56,233 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-ext.html (next: http://localhost:33667/tests/dromaeo/cssquery-jquery.html) 05:15:56 INFO - 2015-12-16 05:15:56,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 144105472 05:15:56 INFO - 2015-12-16 05:15:56,834 DEBUG : BROWSER_OUTPUT: 05:18:58 INFO - 2015-12-16 05:18:58,629 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33667/tests/dromaeo/cssquery-mootools.html) 05:18:59 INFO - 2015-12-16 05:18:59,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 145907712 05:18:59 INFO - 2015-12-16 05:18:59,235 DEBUG : BROWSER_OUTPUT: 05:22:00 INFO - 2015-12-16 05:22:00,994 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33667/tests/dromaeo/cssquery-prototype.html) 05:22:01 INFO - 2015-12-16 05:22:01,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 143425536 05:22:01 INFO - 2015-12-16 05:22:01,603 DEBUG : BROWSER_OUTPUT: 05:25:04 INFO - 2015-12-16 05:25:04,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33667/tests/dromaeo/cssquery-yui.html) 05:25:04 INFO - 2015-12-16 05:25:04,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 144789504 05:25:04 INFO - 2015-12-16 05:25:04,861 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,166 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/dromaeo/cssquery-yui.html (next: http://localhost:33667/tests/dromaeo/cssquery-dojo.html) 05:28:07 INFO - 2015-12-16 05:28:07,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 148520960 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2683.9463210735785;2724.346156922339;2741.6847062587794;2769.7784177265808;2781.0405410133503;4669.533046695329;4838.153323168444;4858.633950737052;4891.241857512083;4900.852974410769;4722.362481065064;4787.640926930486;4813.807447702087;4834.830780922668;4841.951580484194;4331.7400955942585;4416.337549367605;4436.9556304436865;4446.2219111655495;4471.306947423154;4367.061081867398;4389.780510974454;4445.488768791582;4455.042165934324;4462.97768511157;4237.512686041114;4321.870343889688;4380.926672965128;4407.3609326647775;4429.844955426544;4471.5081341052455;4473.709208901443;4474.574915383034;4493.977530112361;4500.369948207253;4433.467983841927;4445.177642136216;4445.555444455561;4466.0844526871915;4472.77636118196;4579.061292435043;4615.907681846378;4700.952990470086;4703.2004559225015;4736.100140973239;5106.489351064901;5154.381474223117;5158;5190.506901844319;5216.217567364888;5158.845234642967;5197.610179236573;5201.921971170435;5216.556592689626;5226.189940559219;4594.701344412602;4731.739754313477;4738.952610473885;4766.142094423037;4795.640326975491;5269.815556455505;5299.920501192485;5304;5310.230016647564;5373.462653734673;4875.731834749052;5112.693238405707;5113.463086375951;5124.743762811919;5126.436092029874;4987.202047672355;5123.948760512421;5174.146265866165;5198.376194856566;5240.423553409122;5102.872428189325;5177.197534382177;5184.611154163453;5186.4035635902355;5230;4910.705357678551;5021.046001259749;5137.203733421251;5154.587632989352;5155.072087024371;4208.978955105266;4271.850485233002;4283.678724095706;4322.113966636832;4331.220380331508;3728;3738.2523495301048;3758.342290099222;3785.205106927521;3791.829367678461;4275.978620106879;4354.325079612665;4374.256376416017;4447.444069491314;4477.350784136282;5236.214567814858;5244.5279924806955;5245.291885595396;5247.55395791362;5262.289590905255;4510.661700372485;4518.909621807611;4521.050579378368;4554.27131658933;4554.63562914966;4378.387025816388;4471.530489298641;4485.730856148642;4581;4612.285095810154;4894.681845680019;4907.33750943618;4921.926171107436;4922.015596880567;4984.102861484823;5168.198929165986;5193.636445448933;5196.9740151299;5202.4017238016595;5250.842474725764;4716.33971244033;4829.348038014823;4835.468098509091;4882.633802464758;4906.607471402208;5087.23691446286;5098.617603679813;5115.335006449138;5122.743862806919;5176.896462070813;4961.206207006861;5206.843794686166;5214.270002199619;5216;5221;4845.079434907357;4936.679115857458;4978.32792572998;4997.20044792846;5043.773030213722;4915.975420122876;4978.502149784992;4989.426215985063;4994.4755800640405;5062.063518249136;4676.134915040729;4863.027394521181;4878.487758785276;4901.877453063559;4911.3369695090705;4815.711057336571;4830.275458681085;4856.538628830255;4865.70805751656;4913.63147763912;4891.241857512091;4910.410750709795;4927.211646136601;4934.55588996992;4956.578690811321 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: |1;ext.html;548.4214154067457;555.7499125393573;558.7792821835372;568.9588053861434;574.959752817303;26176;34149.48775768362;34506.8274658627;34508.827455862745;34688.82655586725;38873.61126388735;39214.80392598033;39239.215215695665;39374.21251574967;39539;7893.407994400429;8042.19578042194;8052.235037671412;8074.475159114668;8108.878366824502;7840.451168418184;7914.28771410573;7923.722669706562;7949.801254968615;7973;7952;7984.720534781284;8055.234752698484;8064.556449395281;8086.878696819553;7932.246436588515;8030.196980301981;8038.799030024267;8043.195680431969;8075.434719569633;7868.213178682143;7892.526448413056;7966.283034526862;7981.441299109065;7982.640781164861;35136.12159696002;35497.46753798695;35812.82093589541;35837.46243806345;35901.46147807785;2930.472514947309;3097.1792474994145;3526.5415495985612;3720.2001569662384;3747.2505498900327;27352.45295094087;27478.313042173904;27632.032878849346;28209.858950705322;29757;3369.090345606697;3375.6624337566045;3399;3432.9485057724155;3491.9825400872833;209769;212910;213474;213801;213959;45797;47125.293120603215;55347;55364.72317638386;57114;49943;49978.250326245136;50002.74998624984;50018.49981500211;50098.499015010115;13757.587272381845;13850.376733046836;13910.095843770325;13930.53729358423;13934.86065139356;10560.683179504627;10631.096356809603;10633.734156646145;10654.467276636136;10733;18826.246950122117;19096.522586935436;19136.712949305773;19188.13653385573;19197.712034319495;7891.881621775678;8078.636461359253;8093.919060809434;8098.959505202436;8114.350851931831;877.8946526416871;880.9955950220336;915.231205787142;986.9834070906977;1041.5417216424748;26194;29324;30078;30082.247943801143;30485.39029219403;914.5884352041475;970.7815741458256;970.9805803884024;971.1939090554822;1066.8346406307035;13003.374578177727;13070.934645326903;13211.207327560378;13221.801672974912;13421.127626704232;309744;317162;318117;318779;319529;46183.30725039127;46298.768506157925;46482.535174648496;46490;46568.767156164;1244.564402459132;1253.8370011898578;1255.773960687085;1256.0454054918328;1267.5056727876135;24256.75743242545;24311.149109781072;24458.877705611358;24509.877450613345;24530.754692452847;37372.62627373692;37597.62402375941;37750.62249377471;37822.81088594649;37913;245971;248493;249364;249791;250257;29077.7092229075;38483.230335392574;38972;39362.2127557453;39626.405603916544;36242.27515449729;37693.62306376901;38203;38384.808075959445;39050.21899561936;32842.83578582092;33458.16354591155;33574.82488112905;33713;34225.82887085548;37427.2514549702;39270;39307.410388844764;40110.398344025445;40340.7982960095;36508.26983460369;38334.12494062901;38805.611943881326;39007;39433.80283098566;28969.42061158723;29976;30372.696273036985;30441.84779076179;30731.38537229198;23567.764322356557;23600.881995589913;23970.40073998164;24013.639795402734;24028 05:28:07 INFO - 2015-12-16 05:28:07,425 DEBUG : BROWSER_OUTPUT: |2;jquery.html;340.4433750817414;340.56067672702216;341.8940128560146;342.8697095103859;343.4213350504399;14927.5521734348;15280.312385942632;16099.597510062255;16700.248488818;16841.578960525992;9689.806203875918;9741.659041933533;9760.170385517222;10139.543720532576;10302.536385862635;4565.223911934962;4689.968206994473;4697.530246975292;4703.5061318561575;4719.69321994071;4625;5178.326817513718;5181;5185.118529849935;5199.610029247802;4680.391549098613;4813.37426134602;4828.589569886564;5145.176771716526;5154.639175257734;4808.110499557593;4823.252395878627;4824.493428190042;4824.5899098576665;4826.01066781309;4770.475247722748;4934.40787105546;4948;4948.90102197954;4957.925631115536;11247.156463265288;11446.885531144748;18676.62646747071;18755.62488750231;18870.433886983414;1760.647870425907;1761.7269323254916;1797.4427927342565;1814.3649722597117;1938.3894073366844;8398.202170793826;8755.956220218859;12135.939320303341;12238.81641775374;12611.243325400414;1658.9585260368467;1704.4290162795478;1773.5122841218767;1774.9201285942033;1835.7613510243716;50155.961931337035;51220.231696524585;51834.740826296;52665.2100218497;52742.73628631832;10148.340357876861;10159.238057145743;10159.847602285972;10180.338278011906;10200.23498237635;12579.308138052313;12681.429335679917;12709.555165569162;12775.552855650008;12786.424610892533;7002.544834585736;7123.43012558994;7235.855282894313;7247.891281630779;7325.084364454443;6654.86690266192;6661.833454163685;6686.5653732507235;6801.21785994607;6822.283660215705;4187.853425130167;4230.2174097792;4255.212785634606;4274.316109422478;4282.743035417885;1667.966640667204;1692.1877498800645;1761.436340371069;1766.275826910986;1774.112943528236;399.2673444229871;402.1594866728493;405.20175254748096;414.4239507085154;420.47860652790934;9488.667896623585;9791.706248812547;9839.803203936024;10041.246906482043;10181.18550515957;407.19172442701216;827.8882350882553;885.751989442957;888.4314039015036;897.2194191053825;8482.618282177316;8615.310775137974;8780.956095219612;8858.15847494487;9029.051949545334;467084;468637;482515;488788;489648;68527;70786;71088.2891171092;71796.64101679562;72490;1044.6448207609449;1084.924055316136;1100.4827730966433;1100.4937728644913;1102.5920409448556;13496.055276130577;13967;14192.148471091768;14407.495737649131;14682.41270349174;30193.087814190858;30671;31550.36899262048;31767.52348714725;31787.20531986719;35541.28917421689;36879.44680829736;37212.25575488529;37566.624333757405;38010;8828.470291782514;9110.817783644423;9265.258779297505;9441.575129119068;9451.05489451077;15630.609234769221;16238.431654892021;16744.33022679128;17031.06329151859;17182.22679979379;13288.468461261828;13415.396307166397;13476.258805765778;13892.23592702411;14188.716225675222;13721.245331506863;13920.164790112625;14019.228942408265;14126.293685315899;14173.22047287409;14451.205183714997;14477.420903163755;14731.337089831197;14904.329305181076;14984.250787460365;8695.478271303742;9479.952600236955;9516.19112375456;9545.809083818423;9558.091981261769;14147.787783183467;14191.148531088167;14426.42294308216;14516.637084072983;14540.272986350852 05:28:07 INFO - 2015-12-16 05:28:07,426 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1491.5152575412994;1644.276518331934;1695.6354383807486;1713.8714596405273;1720.5182548886307;6228.719707613157;6348.428641422271;7217.422606191505;7504.737334193297;7527.24727527247;6797.52417330787;6969.477289203304;7039.472039597025;7042.6126563039015;7046.365827075562;2048.621005114055;2074.98962505188;2076.4601203687075;2081.375587323804;2089.770125286217;2145.9184590946834;2160.0711693971584;2173.3262688566515;2197.967030494544;2208.083645287204;2092.184048221195;2099.4856260216193;2106.4628519727526;2130.1266480742897;2144.07804644003;2088.7389076365453;2111.2399536166968;2111.831053515723;2114.386827819938;2116.058354032456;2056.280301894325;2090.6236877362066;2094.1623350659706;2095.8323334133383;2100.464381582702;7283.526570772883;7347.669354879044;7378.40972722181;7388.519746216479;7426.1459932107955;4196.685248606336;4256.021115143503;4260.893477663052;4272.230998420283;4282.164977829325;2727.836329820197;2739.493193759161;2807.9157625271273;2820.337220753142;2824.887004519817;831.4637059096909;861.7716305179133;873.6636394987912;882.7351794461636;889.9911000889973;9877.06167914047;9905.90094099057;9906.108450239513;9909.355891867006;9961.45212013339;3867.361885288952;3923.92152156961;3976.363781794899;3976.880693579197;3997.420374045805;8470;8474.872876906851;8475.025372082166;8519.318454523624;8524.829503409897;4296.699231053796;4297.871063868089;4312.547182545787;4325.550940434927;4330.242207613655;3235.094173631387;3369.292448585775;3828.7319887608155;3928.626780455852;3946.9210615787524;3436.810975396377;3451.741119416054;3473.6873681368797;3482.3905816482015;3504.123969007748;1097.4512743628186;1110.5113749950158;1116.6873275482878;1119.692084676704;1120.8175374979312;1223.4861358229564;1230.0774419185611;1250.349818094604;1250.562303193893;1263.7472505498754;6663.733450662016;6703.061571380011;6703.363180497845;6708.329167083252;6715.664216789141;422.69777109366953;432.19611522568;438.62935819232695;442.84721770988955;443.5786003296881;5418.349798024262;5428.294321738228;5473.698946557977;5480.534154596824;5491.450854914557;8320.375971802017;8404.159584041547;8431;8449.32405407566;8540.786480338042;8678.305735541142;8841.469511829311;8918.73243802674;8935.463872167691;8958.507282099417;6769.273835172;6882.380585747207;6928.79213623582;6949.166100068024;6968.895466568006;6878.105846239957;6879.243283238739;6879.759208427681;6902.068220790129;6954.3393377629045;7660.119086305146;7852.60736963161;7876.4092693047105;7939.920600794149;7962.243586859471;7807.594632965894;8051.436399452216;8098.068722096798;8098.83802323962;8141.5522146280155;5721.427857214412;6126.387361263838;6848.623325716934;6867.141607299088;6972.477064220305;6788.219354774066;6792.252852186157;6807.5575087619145;6834.589924604539;6842.110525631636;6525.760105579925;6628.734850605922;6659.300773418721;6753.628550429774;6807.421369183675;6233.937660623335;6340.270868850142;6374.713137908712;6459.741610335534;6500.057491663777;6684.331566843277;6707.832304192434;6712.429443497357;6717.932820671731;6719.764808231688;6108.236470441195;6116.541259405473;6137.631742095488;6141.8637552053015;6175.073738939195;6247.781327653511;6266.592671476339;6284.402981716729;6291.779787707408;6307.495400367868 05:28:07 INFO - 2015-12-16 05:28:07,427 DEBUG : BROWSER_OUTPUT: |4;prototype.html;297.91211592580186;306.4896946583938;311.7599448424712;312.11048511741524;313.8273949327869;5579.721013949306;5593.552515798737;5623.606347555673;5741.540676745861;5741.655500669963;5579.107342825148;5612.354579223391;5629.211910332547;5634.887302253953;5649.6327738696955;4164.0630858057;4264.637505812009;4264.936025959613;4274.358846173068;4284.314509678452;4278.8502402415925;4302;4304.504981927072;4305.913881722364;4317.373980772802;4252.6597872170305;4253.978730106361;4258.723182993111;4258.765767882765;4263.211305908401;4262.232798096342;4267.637250833667;4268.445102136702;4269.765162916038;4271.359296105578;3612.422012477991;3706.7590606610484;3714.851405943786;3724.478572999755;3725.590185079639;5253.842384728464;5385.811496597639;5391.838244852661;5401.8379448616215;5403.945960540384;4833.728147770542;4864.586510146642;4875.9760450305475;4876.244182151772;4879.39007624047;5916.171735956927;5922.911156332659;5925.762969481216;5929.822105336847;5937.435943585352;3921.0001449630445;3985.3822657488136;3996.760194388346;4003.339448990913;4003.6396724294664;6140.201773769426;6209.0996805462955;6220.906686399753;6221.9688901555655;6227.034809604518;4082.2243773682903;4099.077707515785;4114.979425102855;4115.9176816463505;4117.732347397409;4180.1848639515;4183.91632167355;4184.937225941613;4192.81132349045;4194.769287689206;4737.952620473751;4786.856394308176;4833.936533962523;4837.346958160603;4840.951590484121;5886.940350736909;5888.793892213752;5889.263842019747;5889.587728858939;5890.823275301662;1476.1217075839859;1486.6283429142713;1489.865912067919;1490.292111247149;1491.2245632271374;988.0317289056765;995.7204991585777;1009.399407352624;1015.827309357411;1022.3099407899638;5092.567131793765;5121.718305493159;5130.666506677054;5139.434662187156;5142.6914385137015;6089.969550152221;6109.725062372115;6121.418465245794;6123.142760013601;6135.478484328882;5107.310513080761;5112.948870511322;5126.769295381717;5129.897402051938;5130.820421285237;3223.4520131577688;3279.2293810954407;3286.1620286826706;3290.4735242361107;3306.991367632847;5686.630369026;5848.298204215436;5856.414358564195;5861.150133230658;5872.031114865999;6350.301466838644;6367.904481432783;6374.9681251594375;6379.5215358847345;6379.840503987344;5842.123681447817;5853.595137167134;5854.151148083419;5861.0915308127305;5868.325142608569;5966.134910438049;6159.599626024474;6205.193324867739;6211.9689401552705;6212.627242365473;6030.849228769316;6064.909026364519;6073.5748497604745;6074.817755467343;6085.087236914498;5827.125931110198;5852.970735146297;5874.676892770939;5874.676892770939;5876.265466816648;4258.446401967724;4322.092360604308;4324.654027677841;4325.653947684241;4327.545607711145;5992.760289588367;6119;6125.632462052291;6130.785422510191;6149.108379284889;5780.94219057804;5833.387494313206;5868.972929737364;5870.5010074142465;5871.500922421642;5829.387914268941;5835.241418615553;5873.794417195378;5885.764569417347;5889.499392551681;5579.46995035471;5723.313202415737;5730.369659337386;5755.309362876482;5765.97117014429;5973.775376047986;5988.461038506555;5990.101484777319;5995.280566332068;6002.0996850472775;5781.82654520365;5834.18321434991;5835.328937172279;5842.5325973921135;5844.47399734026 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: |5;yui.html;657.1883723601353;688.2463702246039;691.9965400172998;707.2255879811607;731.9277258815836;11508.194426390157;11613.47739351729;11678.41607919605;11746.765064698722;11762.941185294072;10961.671149865517;11858.703532411693;11868.050555955502;11912.106592005612;11925.64223073307;801.9839603207932;808.8382323535272;822.7778499805049;822.942394032418;823.555280148719;823.736404350608;825.6697321071559;826.9586520673972;834.0116961400729;835.3734698975768;859.5315553023618;859.8753180788813;866.7876370289256;868.7741187291286;870.3081050564816;855.632078206371;855.8245559660286;858.1332853817657;858.3305022082785;858.9184027517407;632.8765890651325;635.2630948100227;635.5519358851998;638.7317326722788;642.8907085795425;12209.450574724158;12216.511339546407;12289;12330.876691233063;12374.257544547356;10663.893361066444;10914.781704365947;10920.399378034204;11309.377984210865;11463.426828658534;8930.508822014786;9101.77245568866;9161.450312981242;9197.126273004054;9229.53852307382;3249.983750081258;3256.5115232715048;3258.8370581471067;3297.472404415306;3301.4387554115856;40879.591204087876;40941.590584094076;41322.173556529;41384.1723165538;41423;9193.540322983825;9227.26181905446;9388;9397.060293970684;9449.858252126223;15290.541283761506;15519;15534.611634708997;15541.689166216613;15544.922275388551;5944;6025.80490532875;6113.541484388688;6188.0717892315615;6259;3289.5394644749754;3311.2218628622254;3326.750493712933;3328.1346849818738;3353.3628610563915;2538.7588179122954;2688.623592697024;2735.8919637546824;2742.492638861817;2763.488754580409;497.8232727381727;499.8475464983142;500;501.83188631808025;504.9596032317406;575.23493753308;598.374698440131;598.850287428143;608.5983251054372;643.9742410303629;7433.171916463026;7567.394608431312;7724.266194711493;7851.371890248766;7916.445848790645;535.6893002058797;596.4810614765182;601.8766152938637;611.1474493082126;636.3890664961596;3691.3355595992443;3822.197338558878;3877.689784817208;3901.141748815256;3920.2747491714126;47099;47406.76296618495;47461;47493.52506474891;47530;42371.36442953358;42379;42476.15047699029;43418.13163736771;43460;39042.804785976456;39172;39469.40795888064;39853.80073099616;40039.59960400417;7504.512206706547;7510.098788145458;7570.697172113274;7621.961890190513;7795.610219488889;28990.855045724635;29190.416191675624;29508.262293442836;29691.85154074216;29843.701562984093;37003;37008.814955925045;37843.35096543215;38208.617913820504;38390.42414363731;10267.94866025665;10335;10433.530491127765;10469.26715129964;10657.946710266398;11608.767824643628;11722.355270460206;11749.177557570887;11841.881581184078;11912.880871191524;10541.631042913461;10802.408016159143;10919.836202457129;11030.117590592894;11079.667609971713;10551.79426133956;11060.617422822148;11354;11559.711007224887;11586.131040171853;10594.364338139736;10856.782864342827;10918.836217456586;11107.278026928223;11164.385958772345;9625.78552201453;9640.661727069895;9668.613255469983;9702.805943881223;10260.12788912921;9002.144796244347;9069.863952040592;9082.318826087938;9107.362484626012;9607.375520591138 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,334 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: __startTimestamp1450272487418__endTimestamp 05:28:07 INFO - 2015-12-16 05:28:07,428 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,429 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:28:07 INFO - 2015-12-16 05:28:07,429 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:28:07 INFO - 2015-12-16 05:28:07,429 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,429 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4744.85 Median:4865.71 stddev:503.51 (10.3%) stddev-sans-first:478.39 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: Values: 2683.9 2724.3 2741.7 2769.8 2781.0 4669.5 4838.2 4858.6 4891.2 4900.9 4722.4 4787.6 4813.8 4834.8 4842.0 4331.7 4416.3 4437.0 4446.2 4471.3 4367.1 4389.8 4445.5 4455.0 4463.0 4237.5 4321.9 4380.9 4407.4 4429.8 4471.5 4473.7 4474.6 4494.0 4500.4 4433.5 4445.2 4445.6 4466.1 4472.8 4579.1 4615.9 4701.0 4703.2 4736.1 5106.5 5154.4 5158.0 5190.5 5216.2 5158.8 5197.6 5201.9 5216.6 5226.2 4594.7 4731.7 4739.0 4766.1 4795.6 5269.8 5299.9 5304.0 5310.2 5373.5 4875.7 5112.7 5113.5 5124.7 5126.4 4987.2 5123.9 5174.1 5198.4 5240.4 5102.9 5177.2 5184.6 5186.4 5230.0 4910.7 5021.0 5137.2 5154.6 5155.1 4209.0 4271.9 4283.7 4322.1 4331.2 3728.0 3738.3 3758.3 3785.2 3791.8 4276.0 4354.3 4374.3 4447.4 4477.4 5236.2 5244.5 5245.3 5247.6 5262.3 4510.7 4518.9 4521.1 4554.3 4554.6 4378.4 4471.5 4485.7 4581.0 4612.3 4894.7 4907.3 4921.9 4922.0 4984.1 5168.2 5193.6 5197.0 5202.4 5250.8 4716.3 4829.3 4835.5 4882.6 4906.6 5087.2 5098.6 5115.3 5122.7 5176.9 4961.2 5206.8 5214.3 5216.0 5221.0 4845.1 4936.7 4978.3 4997.2 5043.8 4916.0 4978.5 4989.4 4994.5 5062.1 4676.1 4863.0 4878.5 4901.9 4911.3 4815.7 4830.3 4856.5 4865.7 4913.6 4891.2 4910.4 4927.2 4934.6 4956.6 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:41929.89 Median:36071.87 stddev:68673.56 (190.4%) stddev-sans-first:68795.92 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: Values: 548.4 555.7 558.8 569.0 575.0 26176.0 34149.5 34506.8 34508.8 34688.8 38873.6 39214.8 39239.2 39374.2 39539.0 7893.4 8042.2 8052.2 8074.5 8108.9 7840.5 7914.3 7923.7 7949.8 7973.0 7952.0 7984.7 8055.2 8064.6 8086.9 7932.2 8030.2 8038.8 8043.2 8075.4 7868.2 7892.5 7966.3 7981.4 7982.6 35136.1 35497.5 35812.8 35837.5 35901.5 2930.5 3097.2 3526.5 3720.2 3747.3 27352.5 27478.3 27632.0 28209.9 29757.0 3369.1 3375.7 3399.0 3432.9 3492.0 209769.0 212910.0 213474.0 213801.0 213959.0 45797.0 47125.3 55347.0 55364.7 57114.0 49943.0 49978.3 50002.7 50018.5 50098.5 13757.6 13850.4 13910.1 13930.5 13934.9 10560.7 10631.1 10633.7 10654.5 10733.0 18826.2 19096.5 19136.7 19188.1 19197.7 7891.9 8078.6 8093.9 8099.0 8114.4 877.9 881.0 915.2 987.0 1041.5 26194.0 29324.0 30078.0 30082.2 30485.4 914.6 970.8 971.0 971.2 1066.8 13003.4 13070.9 13211.2 13221.8 13421.1 309744.0 317162.0 318117.0 318779.0 319529.0 46183.3 46298.8 46482.5 46490.0 46568.8 1244.6 1253.8 1255.8 1256.0 1267.5 24256.8 24311.1 24458.9 24509.9 24530.8 37372.6 37597.6 37750.6 37822.8 37913.0 245971.0 248493.0 249364.0 249791.0 250257.0 29077.7 38483.2 38972.0 39362.2 39626.4 36242.3 37693.6 38203.0 38384.8 39050.2 32842.8 33458.2 33574.8 33713.0 34225.8 37427.3 39270.0 39307.4 40110.4 40340.8 36508.3 38334.1 38805.6 39007.0 39433.8 28969.4 29976.0 30372.7 30441.8 30731.4 23567.8 23600.9 23970.4 24013.6 24028.0 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25576.23 Median:37788.31 stddev:78255.82 (207.1%) stddev-sans-first:78452.40 05:28:07 INFO - 2015-12-16 05:28:07,430 DEBUG : BROWSER_OUTPUT: Values: 340.4 340.6 341.9 342.9 343.4 14927.6 15280.3 16099.6 16700.2 16841.6 9689.8 9741.7 9760.2 10139.5 10302.5 4565.2 4690.0 4697.5 4703.5 4719.7 4625.0 5178.3 5181.0 5185.1 5199.6 4680.4 4813.4 4828.6 5145.2 5154.6 4808.1 4823.3 4824.5 4824.6 4826.0 4770.5 4934.4 4948.0 4948.9 4957.9 11247.2 11446.9 18676.6 18755.6 18870.4 1760.6 1761.7 1797.4 1814.4 1938.4 8398.2 8756.0 12135.9 12238.8 12611.2 1659.0 1704.4 1773.5 1774.9 1835.8 50156.0 51220.2 51834.7 52665.2 52742.7 10148.3 10159.2 10159.8 10180.3 10200.2 12579.3 12681.4 12709.6 12775.6 12786.4 7002.5 7123.4 7235.9 7247.9 7325.1 6654.9 6661.8 6686.6 6801.2 6822.3 4187.9 4230.2 4255.2 4274.3 4282.7 1668.0 1692.2 1761.4 1766.3 1774.1 399.3 402.2 405.2 414.4 420.5 9488.7 9791.7 9839.8 10041.2 10181.2 407.2 827.9 885.8 888.4 897.2 8482.6 8615.3 8781.0 8858.2 9029.1 467084.0 468637.0 482515.0 488788.0 489648.0 68527.0 70786.0 71088.3 71796.6 72490.0 1044.6 1084.9 1100.5 1100.5 1102.6 13496.1 13967.0 14192.1 14407.5 14682.4 30193.1 30671.0 31550.4 31767.5 31787.2 35541.3 36879.4 37212.3 37566.6 38010.0 8828.5 9110.8 9265.3 9441.6 9451.1 15630.6 16238.4 16744.3 17031.1 17182.2 13288.5 13415.4 13476.3 13892.2 14188.7 13721.2 13920.2 14019.2 14126.3 14173.2 14451.2 14477.4 14731.3 14904.3 14984.3 8695.5 9480.0 9516.2 9545.8 9558.1 14147.8 14191.1 14426.4 14516.6 14540.3 05:28:07 INFO - 2015-12-16 05:28:07,431 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,432 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5074.15 Median:6275.50 stddev:2653.13 (42.3%) stddev-sans-first:2646.91 05:28:07 INFO - 2015-12-16 05:28:07,432 DEBUG : BROWSER_OUTPUT: Values: 1491.5 1644.3 1695.6 1713.9 1720.5 6228.7 6348.4 7217.4 7504.7 7527.2 6797.5 6969.5 7039.5 7042.6 7046.4 2048.6 2075.0 2076.5 2081.4 2089.8 2145.9 2160.1 2173.3 2198.0 2208.1 2092.2 2099.5 2106.5 2130.1 2144.1 2088.7 2111.2 2111.8 2114.4 2116.1 2056.3 2090.6 2094.2 2095.8 2100.5 7283.5 7347.7 7378.4 7388.5 7426.1 4196.7 4256.0 4260.9 4272.2 4282.2 2727.8 2739.5 2807.9 2820.3 2824.9 831.5 861.8 873.7 882.7 890.0 9877.1 9905.9 9906.1 9909.4 9961.5 3867.4 3923.9 3976.4 3976.9 3997.4 8470.0 8474.9 8475.0 8519.3 8524.8 4296.7 4297.9 4312.5 4325.6 4330.2 3235.1 3369.3 3828.7 3928.6 3946.9 3436.8 3451.7 3473.7 3482.4 3504.1 1097.5 1110.5 1116.7 1119.7 1120.8 1223.5 1230.1 1250.3 1250.6 1263.7 6663.7 6703.1 6703.4 6708.3 6715.7 422.7 432.2 438.6 442.8 443.6 5418.3 5428.3 5473.7 5480.5 5491.5 8320.4 8404.2 8431.0 8449.3 8540.8 8678.3 8841.5 8918.7 8935.5 8958.5 6769.3 6882.4 6928.8 6949.2 6968.9 6878.1 6879.2 6879.8 6902.1 6954.3 7660.1 7852.6 7876.4 7939.9 7962.2 7807.6 8051.4 8098.1 8098.8 8141.6 5721.4 6126.4 6848.6 6867.1 6972.5 6788.2 6792.3 6807.6 6834.6 6842.1 6525.8 6628.7 6659.3 6753.6 6807.4 6233.9 6340.3 6374.7 6459.7 6500.1 6684.3 6707.8 6712.4 6717.9 6719.8 6108.2 6116.5 6137.6 6141.9 6175.1 6247.8 6266.6 6284.4 6291.8 6307.5 05:28:07 INFO - 2015-12-16 05:28:07,432 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,432 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4878.04 Median:5586.64 stddev:1459.94 (26.1%) stddev-sans-first:1422.99 05:28:07 INFO - 2015-12-16 05:28:07,433 DEBUG : BROWSER_OUTPUT: Values: 297.9 306.5 311.8 312.1 313.8 5579.7 5593.6 5623.6 5741.5 5741.7 5579.1 5612.4 5629.2 5634.9 5649.6 4164.1 4264.6 4264.9 4274.4 4284.3 4278.9 4302.0 4304.5 4305.9 4317.4 4252.7 4254.0 4258.7 4258.8 4263.2 4262.2 4267.6 4268.4 4269.8 4271.4 3612.4 3706.8 3714.9 3724.5 3725.6 5253.8 5385.8 5391.8 5401.8 5403.9 4833.7 4864.6 4876.0 4876.2 4879.4 5916.2 5922.9 5925.8 5929.8 5937.4 3921.0 3985.4 3996.8 4003.3 4003.6 6140.2 6209.1 6220.9 6222.0 6227.0 4082.2 4099.1 4115.0 4115.9 4117.7 4180.2 4183.9 4184.9 4192.8 4194.8 4738.0 4786.9 4833.9 4837.3 4841.0 5886.9 5888.8 5889.3 5889.6 5890.8 1476.1 1486.6 1489.9 1490.3 1491.2 988.0 995.7 1009.4 1015.8 1022.3 5092.6 5121.7 5130.7 5139.4 5142.7 6090.0 6109.7 6121.4 6123.1 6135.5 5107.3 5112.9 5126.8 5129.9 5130.8 3223.5 3279.2 3286.2 3290.5 3307.0 5686.6 5848.3 5856.4 5861.2 5872.0 6350.3 6367.9 6375.0 6379.5 6379.8 5842.1 5853.6 5854.2 5861.1 5868.3 5966.1 6159.6 6205.2 6212.0 6212.6 6030.8 6064.9 6073.6 6074.8 6085.1 5827.1 5853.0 5874.7 5874.7 5876.3 4258.4 4322.1 4324.7 4325.7 4327.5 5992.8 6119.0 6125.6 6130.8 6149.1 5780.9 5833.4 5869.0 5870.5 5871.5 5829.4 5835.2 5873.8 5885.8 5889.5 5579.5 5723.3 5730.4 5755.3 5766.0 5973.8 5988.5 5990.1 5995.3 6002.1 5781.8 5834.2 5835.3 5842.5 5844.5 05:28:07 INFO - 2015-12-16 05:28:07,433 DEBUG : BROWSER_OUTPUT: 05:28:07 INFO - 2015-12-16 05:28:07,433 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12050.68 Median:41897.18 stddev:13317.60 (31.8%) stddev-sans-first:13327.47 05:28:07 INFO - 2015-12-16 05:28:07,433 DEBUG : BROWSER_OUTPUT: Values: 657.2 688.2 692.0 707.2 731.9 11508.2 11613.5 11678.4 11746.8 11762.9 10961.7 11858.7 11868.1 11912.1 11925.6 802.0 808.8 822.8 822.9 823.6 823.7 825.7 827.0 834.0 835.4 859.5 859.9 866.8 868.8 870.3 855.6 855.8 858.1 858.3 858.9 632.9 635.3 635.6 638.7 642.9 12209.5 12216.5 12289.0 12330.9 12374.3 10663.9 10914.8 10920.4 11309.4 11463.4 8930.5 9101.8 9161.5 9197.1 9229.5 3250.0 3256.5 3258.8 3297.5 3301.4 40879.6 40941.6 41322.2 41384.2 41423.0 9193.5 9227.3 9388.0 9397.1 9449.9 15290.5 15519.0 15534.6 15541.7 15544.9 5944.0 6025.8 6113.5 6188.1 6259.0 3289.5 3311.2 3326.8 3328.1 3353.4 2538.8 2688.6 2735.9 2742.5 2763.5 497.8 499.8 500.0 501.8 505.0 575.2 598.4 598.9 608.6 644.0 7433.2 7567.4 7724.3 7851.4 7916.4 535.7 596.5 601.9 611.1 636.4 3691.3 3822.2 3877.7 3901.1 3920.3 47099.0 47406.8 47461.0 47493.5 47530.0 42371.4 42379.0 42476.2 43418.1 43460.0 39042.8 39172.0 39469.4 39853.8 40039.6 7504.5 7510.1 7570.7 7622.0 7795.6 28990.9 29190.4 29508.3 29691.9 29843.7 37003.0 37008.8 37843.4 38208.6 38390.4 10267.9 10335.0 10433.5 10469.3 10657.9 11608.8 11722.4 11749.2 11841.9 11912.9 10541.6 10802.4 10919.8 11030.1 11079.7 10551.8 11060.6 11354.0 11559.7 11586.1 10594.4 10856.8 10918.8 11107.3 11164.4 9625.8 9640.7 9668.6 9702.8 10260.1 9002.1 9069.9 9082.3 9107.4 9607.4 05:28:07 INFO - 2015-12-16 05:28:07,433 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:28:07 INFO - 2015-12-16 05:28:07,434 DEBUG : BROWSER_OUTPUT: 05:28:08 INFO - 2015-12-16 05:28:08,144 INFO : Browser exited with error code: 0 05:28:08 INFO - 2015-12-16 05:28:08,162 INFO : Completed test dromaeo_css (00:18:34) 05:28:08 INFO - 2015-12-16 05:28:08,163 INFO : Starting test kraken 05:28:08 INFO - 2015-12-16 05:28:08,163 DEBUG : operating with platform_type : linux_ 05:28:08 INFO - 2015-12-16 05:28:08,163 INFO : Initialising browser for kraken test... 05:28:08 INFO - 2015-12-16 05:28:08,169 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP27VqZ/profile http://localhost:33667/getInfo.html 05:28:13 INFO - 2015-12-16 05:28:13,254 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:28:13 INFO - 2015-12-16 05:28:13,255 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:28:13 INFO - 2015-12-16 05:28:13,255 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:28:13 INFO - 2015-12-16 05:28:13,255 DEBUG : BROWSER_OUTPUT: __metrics 05:28:13 INFO - 2015-12-16 05:28:13,392 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:28:14 INFO - 2015-12-16 05:28:14,352 INFO : Browser initialized. 05:28:14 INFO - 2015-12-16 05:28:14,352 INFO : Running cycle 1/1 for kraken test... 05:28:14 INFO - 2015-12-16 05:28:14,352 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP27VqZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:28:26 INFO - 2015-12-16 05:28:26,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 143278080 05:28:26 INFO - 2015-12-16 05:28:26,374 DEBUG : BROWSER_OUTPUT: 05:28:28 INFO - 2015-12-16 05:28:28,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:29 INFO - 2015-12-16 05:28:29,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 144728064 05:28:29 INFO - 2015-12-16 05:28:29,579 DEBUG : BROWSER_OUTPUT: 05:28:32 INFO - 2015-12-16 05:28:32,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-dft) 05:28:32 INFO - 2015-12-16 05:28:32,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 144429056 05:28:32 INFO - 2015-12-16 05:28:32,955 DEBUG : BROWSER_OUTPUT: 05:28:35 INFO - 2015-12-16 05:28:35,866 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-fft) 05:28:36 INFO - 2015-12-16 05:28:36,453 DEBUG : BROWSER_OUTPUT: RSS: Main: 138199040 05:28:36 INFO - 2015-12-16 05:28:36,454 DEBUG : BROWSER_OUTPUT: 05:28:38 INFO - 2015-12-16 05:28:38,511 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33667/tests/kraken/driver.html?testName=audio-oscillator) 05:28:39 INFO - 2015-12-16 05:28:39,097 DEBUG : BROWSER_OUTPUT: RSS: Main: 138383360 05:28:39 INFO - 2015-12-16 05:28:39,097 DEBUG : BROWSER_OUTPUT: 05:28:41 INFO - 2015-12-16 05:28:41,372 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33667/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:41 INFO - 2015-12-16 05:28:41,953 DEBUG : BROWSER_OUTPUT: RSS: Main: 139251712 05:28:41 INFO - 2015-12-16 05:28:41,953 DEBUG : BROWSER_OUTPUT: 05:28:45 INFO - 2015-12-16 05:28:45,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33667/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:45 INFO - 2015-12-16 05:28:45,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 140165120 05:28:45 INFO - 2015-12-16 05:28:45,606 DEBUG : BROWSER_OUTPUT: 05:28:49 INFO - 2015-12-16 05:28:49,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33667/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:49 INFO - 2015-12-16 05:28:49,676 DEBUG : BROWSER_OUTPUT: RSS: Main: 139091968 05:28:49 INFO - 2015-12-16 05:28:49,676 DEBUG : BROWSER_OUTPUT: 05:28:52 INFO - 2015-12-16 05:28:52,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33667/tests/kraken/driver.html?testName=json-parse-financial) 05:28:53 INFO - 2015-12-16 05:28:53,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 139563008 05:28:53 INFO - 2015-12-16 05:28:53,167 DEBUG : BROWSER_OUTPUT: 05:28:54 INFO - 2015-12-16 05:28:54,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33667/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:28:55 INFO - 2015-12-16 05:28:55,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 140263424 05:28:55 INFO - 2015-12-16 05:28:55,545 DEBUG : BROWSER_OUTPUT: 05:28:57 INFO - 2015-12-16 05:28:57,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:28:58 INFO - 2015-12-16 05:28:58,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 139833344 05:28:58 INFO - 2015-12-16 05:28:58,042 DEBUG : BROWSER_OUTPUT: 05:29:00 INFO - 2015-12-16 05:29:00,285 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:00 INFO - 2015-12-16 05:29:00,878 DEBUG : BROWSER_OUTPUT: RSS: Main: 139964416 05:29:00 INFO - 2015-12-16 05:29:00,879 DEBUG : BROWSER_OUTPUT: 05:29:03 INFO - 2015-12-16 05:29:03,507 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:04 INFO - 2015-12-16 05:29:04,109 DEBUG : BROWSER_OUTPUT: RSS: Main: 140357632 05:29:04 INFO - 2015-12-16 05:29:04,109 DEBUG : BROWSER_OUTPUT: 05:29:06 INFO - 2015-12-16 05:29:06,777 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:07 INFO - 2015-12-16 05:29:07,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 140808192 05:29:07 INFO - 2015-12-16 05:29:07,379 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33667/tests/kraken/driver.html?testName=ai-astar) 05:29:09 INFO - 2015-12-16 05:29:09,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 140087296 05:29:09 INFO - 2015-12-16 05:29:09,432 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,433 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:29:09 INFO - 2015-12-16 05:29:09,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:29:09 INFO - 2015-12-16 05:29:09,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:29:09 INFO - 2015-12-16 05:29:09,433 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:29:09 INFO - 2015-12-16 05:29:09,433 DEBUG : BROWSER_OUTPUT: |0;ai-astar;115;110;118;114;117;128;116;115;116;114 05:29:09 INFO - 2015-12-16 05:29:09,434 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;138;153;170;134;158;161;138;172;134 05:29:09 INFO - 2015-12-16 05:29:09,434 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;164;167;164;164;169;168;162;166;162 05:29:09 INFO - 2015-12-16 05:29:09,434 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;76;74;73;75;130;75;73;76;74 05:29:09 INFO - 2015-12-16 05:29:09,434 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;133;94;96;95;134;96;96;128 05:29:09 INFO - 2015-12-16 05:29:09,435 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;109;109;110;110;110;110;110 05:29:09 INFO - 2015-12-16 05:29:09,435 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;149;148;149;149;148;148;149 05:29:09 INFO - 2015-12-16 05:29:09,435 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;97;96;96;96;95;95;97;97;96 05:29:09 INFO - 2015-12-16 05:29:09,435 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;76;82;75;75;73;73;73;75;73 05:29:09 INFO - 2015-12-16 05:29:09,436 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;68;68;68;68;69;70;68;69;69 05:29:09 INFO - 2015-12-16 05:29:09,436 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;88;86;91;86;94;95;88;88;96 05:29:09 INFO - 2015-12-16 05:29:09,436 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;129;135;129;122;123;138;130;138;139 05:29:09 INFO - 2015-12-16 05:29:09,436 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;159;152;153;152;148;144;154;154;159;154 05:29:09 INFO - 2015-12-16 05:29:09,436 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;72;71;70;70;76;71;71;76;71 05:29:09 INFO - 2015-12-16 05:29:09,437 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:29:09 INFO - 2015-12-16 05:29:09,437 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:29:09 INFO - 2015-12-16 05:29:09,437 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,781 05:29:09 INFO - 2015-12-16 05:29:09,437 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:29:09 INFO - 2015-12-16 05:29:09,438 DEBUG : BROWSER_OUTPUT: __startTimestamp1450272549427__endTimestamp 05:29:09 INFO - 2015-12-16 05:29:09,438 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,438 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:29:09 INFO - 2015-12-16 05:29:09,439 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:29:09 INFO - 2015-12-16 05:29:09,439 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,439 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:116.30 Median:116.00 stddev:4.64 (4.0%) stddev-sans-first:4.90 05:29:09 INFO - 2015-12-16 05:29:09,439 DEBUG : BROWSER_OUTPUT: Values: 115.0 110.0 118.0 114.0 117.0 128.0 116.0 115.0 116.0 114.0 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:152.00 Median:159.50 stddev:14.84 (9.3%) stddev-sans-first:15.29 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: Values: 162.0 138.0 153.0 170.0 134.0 158.0 161.0 138.0 172.0 134.0 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:165.00 Median:165.00 stddev:2.40 (1.5%) stddev-sans-first:2.52 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: Values: 164.0 164.0 167.0 164.0 164.0 169.0 168.0 162.0 166.0 162.0 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,440 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.80 Median:74.50 stddev:17.69 (23.7%) stddev-sans-first:18.53 05:29:09 INFO - 2015-12-16 05:29:09,441 DEBUG : BROWSER_OUTPUT: Values: 72.0 76.0 74.0 73.0 75.0 130.0 75.0 73.0 76.0 74.0 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.10 Median:95.00 stddev:17.72 (18.7%) stddev-sans-first:18.25 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 133.0 94.0 96.0 95.0 134.0 96.0 96.0 128.0 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 05:29:09 INFO - 2015-12-16 05:29:09,442 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 149.0 148.0 149.0 149.0 148.0 148.0 149.0 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.50 stddev:0.79 (0.8%) stddev-sans-first:0.78 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: Values: 97.0 97.0 96.0 96.0 96.0 95.0 95.0 97.0 97.0 96.0 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.90 Median:75.00 stddev:2.73 (3.6%) stddev-sans-first:2.87 05:29:09 INFO - 2015-12-16 05:29:09,443 DEBUG : BROWSER_OUTPUT: Values: 74.0 76.0 82.0 75.0 75.0 73.0 73.0 73.0 75.0 73.0 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 68.0 68.0 68.0 69.0 70.0 68.0 69.0 69.0 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:89.50 stddev:4.03 (4.5%) stddev-sans-first:3.90 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: Values: 85.0 88.0 86.0 91.0 86.0 94.0 95.0 88.0 88.0 96.0 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:132.50 stddev:6.50 (4.9%) stddev-sans-first:6.42 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: Values: 124.0 129.0 135.0 129.0 122.0 123.0 138.0 130.0 138.0 139.0 05:29:09 INFO - 2015-12-16 05:29:09,444 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:154.00 stddev:4.51 (2.9%) stddev-sans-first:4.21 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: Values: 159.0 152.0 153.0 152.0 148.0 144.0 154.0 154.0 159.0 154.0 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:71.00 stddev:2.23 (3.1%) stddev-sans-first:2.35 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 71.0 70.0 70.0 76.0 71.0 71.0 76.0 71.0 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:29:09 INFO - 2015-12-16 05:29:09,445 DEBUG : BROWSER_OUTPUT: 05:29:10 INFO - 2015-12-16 05:29:10,364 INFO : Browser exited with error code: 0 05:29:10 INFO - 2015-12-16 05:29:10,415 INFO : Completed test kraken (00:01:02) 05:29:10 INFO - 2015-12-16 05:29:10,415 INFO : Starting test v8_7 05:29:10 INFO - 2015-12-16 05:29:10,416 DEBUG : operating with platform_type : linux_ 05:29:10 INFO - 2015-12-16 05:29:10,416 INFO : Initialising browser for v8_7 test... 05:29:10 INFO - 2015-12-16 05:29:10,422 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy_4li6/profile http://localhost:33667/getInfo.html 05:29:15 INFO - 2015-12-16 05:29:15,500 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:29:15 INFO - 2015-12-16 05:29:15,500 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:29:15 INFO - 2015-12-16 05:29:15,500 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:29:15 INFO - 2015-12-16 05:29:15,500 DEBUG : BROWSER_OUTPUT: __metrics 05:29:15 INFO - 2015-12-16 05:29:15,645 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:29:16 INFO - 2015-12-16 05:29:16,596 INFO : Browser initialized. 05:29:16 INFO - 2015-12-16 05:29:16,596 INFO : Running cycle 1/1 for v8_7 test... 05:29:16 INFO - 2015-12-16 05:29:16,596 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy_4li6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:29:28 INFO - 2015-12-16 05:29:28,596 DEBUG : BROWSER_OUTPUT: RSS: Main: 143482880 05:29:28 INFO - 2015-12-16 05:29:28,597 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33667/tests/v8_7/run.html (next: http://localhost:33667/tests/v8_7/run.html) 05:29:50 INFO - 2015-12-16 05:29:50,334 DEBUG : BROWSER_OUTPUT: RSS: Main: 138321920 05:29:50 INFO - 2015-12-16 05:29:50,334 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,335 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:29:50 INFO - 2015-12-16 05:29:50,335 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:29:50 INFO - 2015-12-16 05:29:50,335 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:29:50 INFO - 2015-12-16 05:29:50,336 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:29:50 INFO - 2015-12-16 05:29:50,336 DEBUG : BROWSER_OUTPUT: |0;Richards;165.48072149594572 05:29:50 INFO - 2015-12-16 05:29:50,336 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.25379770285292 05:29:50 INFO - 2015-12-16 05:29:50,336 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 05:29:50 INFO - 2015-12-16 05:29:50,336 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5159.79381443299 05:29:50 INFO - 2015-12-16 05:29:50,337 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1081.081081081081 05:29:50 INFO - 2015-12-16 05:29:50,338 DEBUG : BROWSER_OUTPUT: |5;Earley;727.802037845706 05:29:50 INFO - 2015-12-16 05:29:50,338 DEBUG : BROWSER_OUTPUT: |6;Boyer;12345.67901234568 05:29:50 INFO - 2015-12-16 05:29:50,338 DEBUG : BROWSER_OUTPUT: |7;RegExp;27405.405405405407 05:29:50 INFO - 2015-12-16 05:29:50,339 DEBUG : BROWSER_OUTPUT: |8;Splay;596.3356973995271 05:29:50 INFO - 2015-12-16 05:29:50,339 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 05:29:50 INFO - 2015-12-16 05:29:50,339 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:29:50 INFO - 2015-12-16 05:29:50,339 DEBUG : BROWSER_OUTPUT: __startTimestamp1450272590334__endTimestamp 05:29:50 INFO - 2015-12-16 05:29:50,339 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,340 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:29:50 INFO - 2015-12-16 05:29:50,340 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:29:50 INFO - 2015-12-16 05:29:50,340 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,340 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.48 Median:165.48 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,340 DEBUG : BROWSER_OUTPUT: Values: 165.5 05:29:50 INFO - 2015-12-16 05:29:50,341 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,341 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.25 Median:185.25 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,341 DEBUG : BROWSER_OUTPUT: Values: 185.3 05:29:50 INFO - 2015-12-16 05:29:50,342 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,342 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,342 DEBUG : BROWSER_OUTPUT: Values: 278.8 05:29:50 INFO - 2015-12-16 05:29:50,342 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,342 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5159.79 Median:5159.79 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,342 DEBUG : BROWSER_OUTPUT: Values: 5159.8 05:29:50 INFO - 2015-12-16 05:29:50,343 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,343 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1081.08 Median:1081.08 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,343 DEBUG : BROWSER_OUTPUT: Values: 1081.1 05:29:50 INFO - 2015-12-16 05:29:50,343 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,343 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:727.80 Median:727.80 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,344 DEBUG : BROWSER_OUTPUT: Values: 727.8 05:29:50 INFO - 2015-12-16 05:29:50,344 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,344 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12345.68 Median:12345.68 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,344 DEBUG : BROWSER_OUTPUT: Values: 12345.7 05:29:50 INFO - 2015-12-16 05:29:50,344 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,345 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27405.41 Median:27405.41 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,345 DEBUG : BROWSER_OUTPUT: Values: 27405.4 05:29:50 INFO - 2015-12-16 05:29:50,345 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,345 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:596.34 Median:596.34 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,345 DEBUG : BROWSER_OUTPUT: Values: 596.3 05:29:50 INFO - 2015-12-16 05:29:50,346 DEBUG : BROWSER_OUTPUT: 05:29:50 INFO - 2015-12-16 05:29:50,346 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:29:50 INFO - 2015-12-16 05:29:50,346 DEBUG : BROWSER_OUTPUT: Values: 4830.9 05:29:50 INFO - 2015-12-16 05:29:50,346 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:29:50 INFO - 2015-12-16 05:29:50,346 DEBUG : BROWSER_OUTPUT: 05:29:51 INFO - 2015-12-16 05:29:51,248 INFO : Browser exited with error code: 0 05:29:51 INFO - 2015-12-16 05:29:51,262 INFO : Completed test v8_7 (00:00:40) 05:29:51 INFO - 2015-12-16 05:29:51,330 INFO : Completed test suite (00:20:17) 05:29:51 INFO - 2015-12-16 05:29:51,330 DEBUG : Working with test: dromaeo_css 05:29:51 INFO - 2015-12-16 05:29:51,330 DEBUG : Generating results file: dromaeo_css 05:29:51 INFO - 2015-12-16 05:29:51,331 DEBUG : Working with test: kraken 05:29:51 INFO - 2015-12-16 05:29:51,332 DEBUG : Generating results file: kraken 05:29:51 INFO - 2015-12-16 05:29:51,332 INFO : javascript benchmark 05:29:51 INFO - 2015-12-16 05:29:51,332 DEBUG : Working with test: v8_7 05:29:51 INFO - 2015-12-16 05:29:51,332 DEBUG : Generating results file: v8_7 05:29:51 INFO - 2015-12-16 05:29:51,332 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:51 INFO - 2015-12-16 05:29:51,391 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 05:29:51 INFO - 2015-12-16 05:29:51,391 DEBUG : process_Request line: dromaeo_css 6356.98 graph.html#tests=[[72,132,43]] 05:29:51 INFO - 2015-12-16 05:29:51,391 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:51 INFO - 2015-12-16 05:29:51,427 DEBUG : process_Request line: kraken 1562.40 graph.html#tests=[[232,132,43]] 05:29:51 INFO - 2015-12-16 05:29:51,428 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:51 INFO - 2015-12-16 05:29:51,462 DEBUG : process_Request line: v8_7 21214.32 graph.html#tests=[[230,132,43]] 05:29:51 INFO - 2015-12-16 05:29:51,463 INFO : javascript benchmark 05:29:51 INFO - 2015-12-16 05:29:51,467 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2683.9463210735785, 2724.346156922339, 2741.6847062587794, 2769.7784177265808, 2781.0405410133503, 4669.533046695329, 4838.153323168444, 4858.633950737052, 4891.241857512083, 4900.852974410769, 4722.362481065064, 4787.640926930486, 4813.807447702087, 4834.830780922668, 4841.951580484194, 4331.7400955942585, 4416.337549367605, 4436.9556304436865, 4446.2219111655495, 4471.306947423154, 4367.061081867398, 4389.780510974454, 4445.488768791582, 4455.042165934324, 4462.97768511157, 4237.512686041114, 4321.870343889688, 4380.926672965128, 4407.3609326647775, 4429.844955426544, 4471.5081341052455, 4473.709208901443, 4474.574915383034, 4493.977530112361, 4500.369948207253, 4433.467983841927, 4445.177642136216, 4445.555444455561, 4466.0844526871915, 4472.77636118196, 4579.061292435043, 4615.907681846378, 4700.952990470086, 4703.2004559225015, 4736.100140973239, 5106.489351064901, 5154.381474223117, 5158.0, 5190.506901844319, 5216.217567364888, 5158.845234642967, 5197.610179236573, 5201.921971170435, 5216.556592689626, 5226.189940559219, 4594.701344412602, 4731.739754313477, 4738.952610473885, 4766.142094423037, 4795.640326975491, 5269.815556455505, 5299.920501192485, 5304.0, 5310.230016647564, 5373.462653734673, 4875.731834749052, 5112.693238405707, 5113.463086375951, 5124.743762811919, 5126.436092029874, 4987.202047672355, 5123.948760512421, 5174.146265866165, 5198.376194856566, 5240.423553409122, 5102.872428189325, 5177.197534382177, 5184.611154163453, 5186.4035635902355, 5230.0, 4910.705357678551, 5021.046001259749, 5137.203733421251, 5154.587632989352, 5155.072087024371, 4208.978955105266, 4271.850485233002, 4283.678724095706, 4322.113966636832, 4331.220380331508, 3728.0, 3738.2523495301048, 3758.342290099222, 3785.205106927521, 3791.829367678461, 4275.978620106879, 4354.325079612665, 4374.256376416017, 4447.444069491314, 4477.350784136282, 5236.214567814858, 5244.5279924806955, 5245.291885595396, 5247.55395791362, 5262.289590905255, 4510.661700372485, 4518.909621807611, 4521.050579378368, 4554.27131658933, 4554.63562914966, 4378.387025816388, 4471.530489298641, 4485.730856148642, 4581.0, 4612.285095810154, 4894.681845680019, 4907.33750943618, 4921.926171107436, 4922.015596880567, 4984.102861484823, 5168.198929165986, 5193.636445448933, 5196.9740151299, 5202.4017238016595, 5250.842474725764, 4716.33971244033, 4829.348038014823, 4835.468098509091, 4882.633802464758, 4906.607471402208, 5087.23691446286, 5098.617603679813, 5115.335006449138, 5122.743862806919, 5176.896462070813, 4961.206207006861, 5206.843794686166, 5214.270002199619, 5216.0, 5221.0, 4845.079434907357, 4936.679115857458, 4978.32792572998, 4997.20044792846, 5043.773030213722, 4915.975420122876, 4978.502149784992, 4989.426215985063, 4994.4755800640405, 5062.063518249136, 4676.134915040729, 4863.027394521181, 4878.487758785276, 4901.877453063559, 4911.3369695090705, 4815.711057336571, 4830.275458681085, 4856.538628830255, 4865.70805751656, 4913.63147763912, 4891.241857512091, 4910.410750709795, 4927.211646136601, 4934.55588996992, 4956.578690811321], "unit": "score", "name": "dojo.html", "value": 4713.124871558497}, {"lowerIsBetter": false, "replicates": [548.4214154067457, 555.7499125393573, 558.7792821835372, 568.9588053861434, 574.959752817303, 26176.0, 34149.48775768362, 34506.8274658627, 34508.827455862745, 34688.82655586725, 38873.61126388735, 39214.80392598033, 39239.215215695665, 39374.21251574967, 39539.0, 7893.407994400429, 8042.19578042194, 8052.235037671412, 8074.475159114668, 8108.878366824502, 7840.451168418184, 7914.28771410573, 7923.722669706562, 7949.801254968615, 7973.0, 7952.0, 7984.720534781284, 8055.234752698484, 8064.556449395281, 8086.878696819553, 7932.246436588515, 8030.196980301981, 8038.799030024267, 8043.195680431969, 8075.434719569633, 7868.213178682143, 7892.526448413056, 7966.283034526862, 7981.441299109065, 7982.640781164861, 35136.12159696002, 35497.46753798695, 35812.82093589541, 35837.46243806345, 35901.46147807785, 2930.472514947309, 3097.1792474994145, 3526.5415495985612, 3720.2001569662384, 3747.2505498900327, 27352.45295094087, 27478.313042173904, 27632.032878849346, 28209.858950705322, 29757.0, 3369.090345606697, 3375.6624337566045, 3399.0, 3432.9485057724155, 3491.9825400872833, 209769.0, 212910.0, 213474.0, 213801.0, 213959.0, 45797.0, 47125.293120603215, 55347.0, 55364.72317638386, 57114.0, 49943.0, 49978.250326245136, 50002.74998624984, 50018.49981500211, 50098.499015010115, 13757.587272381845, 13850.376733046836, 13910.095843770325, 13930.53729358423, 13934.86065139356, 10560.683179504627, 10631.096356809603, 10633.734156646145, 10654.467276636136, 10733.0, 18826.246950122117, 19096.522586935436, 19136.712949305773, 19188.13653385573, 19197.712034319495, 7891.881621775678, 8078.636461359253, 8093.919060809434, 8098.959505202436, 8114.350851931831, 877.8946526416871, 880.9955950220336, 915.231205787142, 986.9834070906977, 1041.5417216424748, 26194.0, 29324.0, 30078.0, 30082.247943801143, 30485.39029219403, 914.5884352041475, 970.7815741458256, 970.9805803884024, 971.1939090554822, 1066.8346406307035, 13003.374578177727, 13070.934645326903, 13211.207327560378, 13221.801672974912, 13421.127626704232, 309744.0, 317162.0, 318117.0, 318779.0, 319529.0, 46183.30725039127, 46298.768506157925, 46482.535174648496, 46490.0, 46568.767156164, 1244.564402459132, 1253.8370011898578, 1255.773960687085, 1256.0454054918328, 1267.5056727876135, 24256.75743242545, 24311.149109781072, 24458.877705611358, 24509.877450613345, 24530.754692452847, 37372.62627373692, 37597.62402375941, 37750.62249377471, 37822.81088594649, 37913.0, 245971.0, 248493.0, 249364.0, 249791.0, 250257.0, 29077.7092229075, 38483.230335392574, 38972.0, 39362.2127557453, 39626.405603916544, 36242.27515449729, 37693.62306376901, 38203.0, 38384.808075959445, 39050.21899561936, 32842.83578582092, 33458.16354591155, 33574.82488112905, 33713.0, 34225.82887085548, 37427.2514549702, 39270.0, 39307.410388844764, 40110.398344025445, 40340.7982960095, 36508.26983460369, 38334.12494062901, 38805.611943881326, 39007.0, 39433.80283098566, 28969.42061158723, 29976.0, 30372.696273036985, 30441.84779076179, 30731.38537229198, 23567.764322356557, 23600.881995589913, 23970.40073998164, 24013.639795402734, 24028.0], "unit": "score", "name": "ext.html", "value": 17139.618492177673}, {"lowerIsBetter": false, "replicates": [340.4433750817414, 340.56067672702216, 341.8940128560146, 342.8697095103859, 343.4213350504399, 14927.5521734348, 15280.312385942632, 16099.597510062255, 16700.248488818, 16841.578960525992, 9689.806203875918, 9741.659041933533, 9760.170385517222, 10139.543720532576, 10302.536385862635, 4565.223911934962, 4689.968206994473, 4697.530246975292, 4703.5061318561575, 4719.69321994071, 4625.0, 5178.326817513718, 5181.0, 5185.118529849935, 5199.610029247802, 4680.391549098613, 4813.37426134602, 4828.589569886564, 5145.176771716526, 5154.639175257734, 4808.110499557593, 4823.252395878627, 4824.493428190042, 4824.5899098576665, 4826.01066781309, 4770.475247722748, 4934.40787105546, 4948.0, 4948.90102197954, 4957.925631115536, 11247.156463265288, 11446.885531144748, 18676.62646747071, 18755.62488750231, 18870.433886983414, 1760.647870425907, 1761.7269323254916, 1797.4427927342565, 1814.3649722597117, 1938.3894073366844, 8398.202170793826, 8755.956220218859, 12135.939320303341, 12238.81641775374, 12611.243325400414, 1658.9585260368467, 1704.4290162795478, 1773.5122841218767, 1774.9201285942033, 1835.7613510243716, 50155.961931337035, 51220.231696524585, 51834.740826296, 52665.2100218497, 52742.73628631832, 10148.340357876861, 10159.238057145743, 10159.847602285972, 10180.338278011906, 10200.23498237635, 12579.308138052313, 12681.429335679917, 12709.555165569162, 12775.552855650008, 12786.424610892533, 7002.544834585736, 7123.43012558994, 7235.855282894313, 7247.891281630779, 7325.084364454443, 6654.86690266192, 6661.833454163685, 6686.5653732507235, 6801.21785994607, 6822.283660215705, 4187.853425130167, 4230.2174097792, 4255.212785634606, 4274.316109422478, 4282.743035417885, 1667.966640667204, 1692.1877498800645, 1761.436340371069, 1766.275826910986, 1774.112943528236, 399.2673444229871, 402.1594866728493, 405.20175254748096, 414.4239507085154, 420.47860652790934, 9488.667896623585, 9791.706248812547, 9839.803203936024, 10041.246906482043, 10181.18550515957, 407.19172442701216, 827.8882350882553, 885.751989442957, 888.4314039015036, 897.2194191053825, 8482.618282177316, 8615.310775137974, 8780.956095219612, 8858.15847494487, 9029.051949545334, 467084.0, 468637.0, 482515.0, 488788.0, 489648.0, 68527.0, 70786.0, 71088.2891171092, 71796.64101679562, 72490.0, 1044.6448207609449, 1084.924055316136, 1100.4827730966433, 1100.4937728644913, 1102.5920409448556, 13496.055276130577, 13967.0, 14192.148471091768, 14407.495737649131, 14682.41270349174, 30193.087814190858, 30671.0, 31550.36899262048, 31767.52348714725, 31787.20531986719, 35541.28917421689, 36879.44680829736, 37212.25575488529, 37566.624333757405, 38010.0, 8828.470291782514, 9110.817783644423, 9265.258779297505, 9441.575129119068, 9451.05489451077, 15630.609234769221, 16238.431654892021, 16744.33022679128, 17031.06329151859, 17182.22679979379, 13288.468461261828, 13415.396307166397, 13476.258805765778, 13892.23592702411, 14188.716225675222, 13721.245331506863, 13920.164790112625, 14019.228942408265, 14126.293685315899, 14173.22047287409, 14451.205183714997, 14477.420903163755, 14731.337089831197, 14904.329305181076, 14984.250787460365, 8695.478271303742, 9479.952600236955, 9516.19112375456, 9545.809083818423, 9558.091981261769, 14147.787783183467, 14191.148531088167, 14426.42294308216, 14516.637084072983, 14540.272986350852], "unit": "score", "name": "jquery.html", "value": 7878.703539126607}, {"lowerIsBetter": false, "replicates": [1491.5152575412994, 1644.276518331934, 1695.6354383807486, 1713.8714596405273, 1720.5182548886307, 6228.719707613157, 6348.428641422271, 7217.422606191505, 7504.737334193297, 7527.24727527247, 6797.52417330787, 6969.477289203304, 7039.472039597025, 7042.6126563039015, 7046.365827075562, 2048.621005114055, 2074.98962505188, 2076.4601203687075, 2081.375587323804, 2089.770125286217, 2145.9184590946834, 2160.0711693971584, 2173.3262688566515, 2197.967030494544, 2208.083645287204, 2092.184048221195, 2099.4856260216193, 2106.4628519727526, 2130.1266480742897, 2144.07804644003, 2088.7389076365453, 2111.2399536166968, 2111.831053515723, 2114.386827819938, 2116.058354032456, 2056.280301894325, 2090.6236877362066, 2094.1623350659706, 2095.8323334133383, 2100.464381582702, 7283.526570772883, 7347.669354879044, 7378.40972722181, 7388.519746216479, 7426.1459932107955, 4196.685248606336, 4256.021115143503, 4260.893477663052, 4272.230998420283, 4282.164977829325, 2727.836329820197, 2739.493193759161, 2807.9157625271273, 2820.337220753142, 2824.887004519817, 831.4637059096909, 861.7716305179133, 873.6636394987912, 882.7351794461636, 889.9911000889973, 9877.06167914047, 9905.90094099057, 9906.108450239513, 9909.355891867006, 9961.45212013339, 3867.361885288952, 3923.92152156961, 3976.363781794899, 3976.880693579197, 3997.420374045805, 8470.0, 8474.872876906851, 8475.025372082166, 8519.318454523624, 8524.829503409897, 4296.699231053796, 4297.871063868089, 4312.547182545787, 4325.550940434927, 4330.242207613655, 3235.094173631387, 3369.292448585775, 3828.7319887608155, 3928.626780455852, 3946.9210615787524, 3436.810975396377, 3451.741119416054, 3473.6873681368797, 3482.3905816482015, 3504.123969007748, 1097.4512743628186, 1110.5113749950158, 1116.6873275482878, 1119.692084676704, 1120.8175374979312, 1223.4861358229564, 1230.0774419185611, 1250.349818094604, 1250.562303193893, 1263.7472505498754, 6663.733450662016, 6703.061571380011, 6703.363180497845, 6708.329167083252, 6715.664216789141, 422.69777109366953, 432.19611522568, 438.62935819232695, 442.84721770988955, 443.5786003296881, 5418.349798024262, 5428.294321738228, 5473.698946557977, 5480.534154596824, 5491.450854914557, 8320.375971802017, 8404.159584041547, 8431.0, 8449.32405407566, 8540.786480338042, 8678.305735541142, 8841.469511829311, 8918.73243802674, 8935.463872167691, 8958.507282099417, 6769.273835172, 6882.380585747207, 6928.79213623582, 6949.166100068024, 6968.895466568006, 6878.105846239957, 6879.243283238739, 6879.759208427681, 6902.068220790129, 6954.3393377629045, 7660.119086305146, 7852.60736963161, 7876.4092693047105, 7939.920600794149, 7962.243586859471, 7807.594632965894, 8051.436399452216, 8098.068722096798, 8098.83802323962, 8141.5522146280155, 5721.427857214412, 6126.387361263838, 6848.623325716934, 6867.141607299088, 6972.477064220305, 6788.219354774066, 6792.252852186157, 6807.5575087619145, 6834.589924604539, 6842.110525631636, 6525.760105579925, 6628.734850605922, 6659.300773418721, 6753.628550429774, 6807.421369183675, 6233.937660623335, 6340.270868850142, 6374.713137908712, 6459.741610335534, 6500.057491663777, 6684.331566843277, 6707.832304192434, 6712.429443497357, 6717.932820671731, 6719.764808231688, 6108.236470441195, 6116.541259405473, 6137.631742095488, 6141.8637552053015, 6175.073738939195, 6247.781327653511, 6266.592671476339, 6284.402981716729, 6291.779787707408, 6307.495400367868], "unit": "score", "name": "mootools.html", "value": 4098.2780785733785}, {"lowerIsBetter": false, "replicates": [297.91211592580186, 306.4896946583938, 311.7599448424712, 312.11048511741524, 313.8273949327869, 5579.721013949306, 5593.552515798737, 5623.606347555673, 5741.540676745861, 5741.655500669963, 5579.107342825148, 5612.354579223391, 5629.211910332547, 5634.887302253953, 5649.6327738696955, 4164.0630858057, 4264.637505812009, 4264.936025959613, 4274.358846173068, 4284.314509678452, 4278.8502402415925, 4302.0, 4304.504981927072, 4305.913881722364, 4317.373980772802, 4252.6597872170305, 4253.978730106361, 4258.723182993111, 4258.765767882765, 4263.211305908401, 4262.232798096342, 4267.637250833667, 4268.445102136702, 4269.765162916038, 4271.359296105578, 3612.422012477991, 3706.7590606610484, 3714.851405943786, 3724.478572999755, 3725.590185079639, 5253.842384728464, 5385.811496597639, 5391.838244852661, 5401.8379448616215, 5403.945960540384, 4833.728147770542, 4864.586510146642, 4875.9760450305475, 4876.244182151772, 4879.39007624047, 5916.171735956927, 5922.911156332659, 5925.762969481216, 5929.822105336847, 5937.435943585352, 3921.0001449630445, 3985.3822657488136, 3996.760194388346, 4003.339448990913, 4003.6396724294664, 6140.201773769426, 6209.0996805462955, 6220.906686399753, 6221.9688901555655, 6227.034809604518, 4082.2243773682903, 4099.077707515785, 4114.979425102855, 4115.9176816463505, 4117.732347397409, 4180.1848639515, 4183.91632167355, 4184.937225941613, 4192.81132349045, 4194.769287689206, 4737.952620473751, 4786.856394308176, 4833.936533962523, 4837.346958160603, 4840.951590484121, 5886.940350736909, 5888.793892213752, 5889.263842019747, 5889.587728858939, 5890.823275301662, 1476.1217075839859, 1486.6283429142713, 1489.865912067919, 1490.292111247149, 1491.2245632271374, 988.0317289056765, 995.7204991585777, 1009.399407352624, 1015.827309357411, 1022.3099407899638, 5092.567131793765, 5121.718305493159, 5130.666506677054, 5139.434662187156, 5142.6914385137015, 6089.969550152221, 6109.725062372115, 6121.418465245794, 6123.142760013601, 6135.478484328882, 5107.310513080761, 5112.948870511322, 5126.769295381717, 5129.897402051938, 5130.820421285237, 3223.4520131577688, 3279.2293810954407, 3286.1620286826706, 3290.4735242361107, 3306.991367632847, 5686.630369026, 5848.298204215436, 5856.414358564195, 5861.150133230658, 5872.031114865999, 6350.301466838644, 6367.904481432783, 6374.9681251594375, 6379.5215358847345, 6379.840503987344, 5842.123681447817, 5853.595137167134, 5854.151148083419, 5861.0915308127305, 5868.325142608569, 5966.134910438049, 6159.599626024474, 6205.193324867739, 6211.9689401552705, 6212.627242365473, 6030.849228769316, 6064.909026364519, 6073.5748497604745, 6074.817755467343, 6085.087236914498, 5827.125931110198, 5852.970735146297, 5874.676892770939, 5874.676892770939, 5876.265466816648, 4258.446401967724, 4322.092360604308, 4324.654027677841, 4325.653947684241, 4327.545607711145, 5992.760289588367, 6119.0, 6125.632462052291, 6130.785422510191, 6149.108379284889, 5780.94219057804, 5833.387494313206, 5868.972929737364, 5870.5010074142465, 5871.500922421642, 5829.387914268941, 5835.241418615553, 5873.794417195378, 5885.764569417347, 5889.499392551681, 5579.46995035471, 5723.313202415737, 5730.369659337386, 5755.309362876482, 5765.97117014429, 5973.775376047986, 5988.461038506555, 5990.101484777319, 5995.280566332068, 6002.0996850472775, 5781.82654520365, 5834.18321434991, 5835.328937172279, 5842.5325973921135, 5844.47399734026], "unit": "score", "name": "prototype.html", "value": 4404.320828695949}, {"lowerIsBetter": false, "replicates": [657.1883723601353, 688.2463702246039, 691.9965400172998, 707.2255879811607, 731.9277258815836, 11508.194426390157, 11613.47739351729, 11678.41607919605, 11746.765064698722, 11762.941185294072, 10961.671149865517, 11858.703532411693, 11868.050555955502, 11912.106592005612, 11925.64223073307, 801.9839603207932, 808.8382323535272, 822.7778499805049, 822.942394032418, 823.555280148719, 823.736404350608, 825.6697321071559, 826.9586520673972, 834.0116961400729, 835.3734698975768, 859.5315553023618, 859.8753180788813, 866.7876370289256, 868.7741187291286, 870.3081050564816, 855.632078206371, 855.8245559660286, 858.1332853817657, 858.3305022082785, 858.9184027517407, 632.8765890651325, 635.2630948100227, 635.5519358851998, 638.7317326722788, 642.8907085795425, 12209.450574724158, 12216.511339546407, 12289.0, 12330.876691233063, 12374.257544547356, 10663.893361066444, 10914.781704365947, 10920.399378034204, 11309.377984210865, 11463.426828658534, 8930.508822014786, 9101.77245568866, 9161.450312981242, 9197.126273004054, 9229.53852307382, 3249.983750081258, 3256.5115232715048, 3258.8370581471067, 3297.472404415306, 3301.4387554115856, 40879.591204087876, 40941.590584094076, 41322.173556529, 41384.1723165538, 41423.0, 9193.540322983825, 9227.26181905446, 9388.0, 9397.060293970684, 9449.858252126223, 15290.541283761506, 15519.0, 15534.611634708997, 15541.689166216613, 15544.922275388551, 5944.0, 6025.80490532875, 6113.541484388688, 6188.0717892315615, 6259.0, 3289.5394644749754, 3311.2218628622254, 3326.750493712933, 3328.1346849818738, 3353.3628610563915, 2538.7588179122954, 2688.623592697024, 2735.8919637546824, 2742.492638861817, 2763.488754580409, 497.8232727381727, 499.8475464983142, 500.0, 501.83188631808025, 504.9596032317406, 575.23493753308, 598.374698440131, 598.850287428143, 608.5983251054372, 643.9742410303629, 7433.171916463026, 7567.394608431312, 7724.266194711493, 7851.371890248766, 7916.445848790645, 535.6893002058797, 596.4810614765182, 601.8766152938637, 611.1474493082126, 636.3890664961596, 3691.3355595992443, 3822.197338558878, 3877.689784817208, 3901.141748815256, 3920.2747491714126, 47099.0, 47406.76296618495, 47461.0, 47493.52506474891, 47530.0, 42371.36442953358, 42379.0, 42476.15047699029, 43418.13163736771, 43460.0, 39042.804785976456, 39172.0, 39469.40795888064, 39853.80073099616, 40039.59960400417, 7504.512206706547, 7510.098788145458, 7570.697172113274, 7621.961890190513, 7795.610219488889, 28990.855045724635, 29190.416191675624, 29508.262293442836, 29691.85154074216, 29843.701562984093, 37003.0, 37008.814955925045, 37843.35096543215, 38208.617913820504, 38390.42414363731, 10267.94866025665, 10335.0, 10433.530491127765, 10469.26715129964, 10657.946710266398, 11608.767824643628, 11722.355270460206, 11749.177557570887, 11841.881581184078, 11912.880871191524, 10541.631042913461, 10802.408016159143, 10919.836202457129, 11030.117590592894, 11079.667609971713, 10551.79426133956, 11060.617422822148, 11354.0, 11559.711007224887, 11586.131040171853, 10594.364338139736, 10856.782864342827, 10918.836217456586, 11107.278026928223, 11164.385958772345, 9625.78552201453, 9640.661727069895, 9668.613255469983, 9702.805943881223, 10260.12788912921, 9002.144796244347, 9069.863952040592, 9082.318826087938, 9107.362484626012, 9607.375520591138], "unit": "score", "name": "yui.html", "value": 5744.036987537628}], "name": "dromaeo_css", "value": 6356.98062518778}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 110.0, 118.0, 114.0, 117.0, 128.0, 116.0, 115.0, 116.0, 114.0], "unit": "score", "name": "ai-astar", "value": 116.3}, {"lowerIsBetter": true, "replicates": [162.0, 138.0, 153.0, 170.0, 134.0, 158.0, 161.0, 138.0, 172.0, 134.0], "unit": "score", "name": "audio-beat-detection", "value": 152.0}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 167.0, 164.0, 164.0, 169.0, 168.0, 162.0, 166.0, 162.0], "unit": "score", "name": "audio-dft", "value": 165.0}, {"lowerIsBetter": true, "replicates": [72.0, 76.0, 74.0, 73.0, 75.0, 130.0, 75.0, 73.0, 76.0, 74.0], "unit": "score", "name": "audio-fft", "value": 79.8}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 133.0, 94.0, 96.0, 95.0, 134.0, 96.0, 96.0, 128.0], "unit": "score", "name": "audio-oscillator", "value": 106.1}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 149.0, 148.0, 149.0, 149.0, 148.0, 148.0, 149.0], "unit": "score", "name": "imaging-darkroom", "value": 148.4}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 96.0, 96.0, 96.0, 95.0, 95.0, 97.0, 97.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [74.0, 76.0, 82.0, 75.0, 75.0, 73.0, 73.0, 73.0, 75.0, 73.0], "unit": "score", "name": "json-parse-financial", "value": 74.9}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 68.0, 68.0, 69.0, 70.0, 68.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.6}, {"lowerIsBetter": true, "replicates": [85.0, 88.0, 86.0, 91.0, 86.0, 94.0, 95.0, 88.0, 88.0, 96.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.7}, {"lowerIsBetter": true, "replicates": [124.0, 129.0, 135.0, 129.0, 122.0, 123.0, 138.0, 130.0, 138.0, 139.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 130.7}, {"lowerIsBetter": true, "replicates": [159.0, 152.0, 153.0, 152.0, 148.0, 144.0, 154.0, 154.0, 159.0, 154.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.9}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 71.0, 70.0, 70.0, 76.0, 71.0, 71.0, 76.0, 71.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.9}], "name": "kraken", "value": 1562.4000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.48072149594572], "unit": "score", "name": "Richards", "value": 213.32998600000002}, {"lowerIsBetter": false, "replicates": [185.25379770285292], "unit": "score", "name": "DeltaBlue", "value": 356.90496399999995}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5159.79381443299], "unit": "score", "name": "Decrypt", "value": 51.587526473526495}, {"lowerIsBetter": false, "replicates": [1081.081081081081], "unit": "score", "name": "RayTrace", "value": 684.4898250000002}, {"lowerIsBetter": false, "replicates": [727.802037845706], "unit": "score", "name": "Earley", "value": 915.720162}, {"lowerIsBetter": false, "replicates": [12345.67901234568], "unit": "score", "name": "Boyer", "value": 53.98350299999995}, {"lowerIsBetter": false, "replicates": [27405.405405405407], "unit": "score", "name": "RegExp", "value": 33.241070019723885}, {"lowerIsBetter": false, "replicates": [596.3356973995271], "unit": "score", "name": "Splay", "value": 136.65289593657087}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21214.321172589927}]} 05:29:51 INFO - RETURN: dromaeo_css: 6356.98 05:29:51 INFO - RETURN: kraken: 1562.40 05:29:51 INFO - RETURN: v8_7: 21214.32 05:29:51 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21214.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1562.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6356.98"}}} 05:29:51 INFO - Return code: 0 05:29:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:29:51 INFO - # TBPL SUCCESS # 05:29:51 INFO - Running post-action listener: _resource_record_post_action 05:29:51 INFO - Running post-run listener: _resource_record_post_run 05:29:52 INFO - Total resource usage - Wall time: 1234s; CPU: 13.0%; Read bytes: 8192; Write bytes: 516435968; Read time: 124; Write time: 951072 05:29:52 INFO - install - Wall time: 15s; CPU: 18.0%; Read bytes: 4096; Write bytes: 240185344; Read time: 108; Write time: 744952 05:29:52 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 4096; Write bytes: 276250624; Read time: 16; Write time: 206120 05:29:52 INFO - Running post-run listener: _upload_blobber_files 05:29:52 INFO - Blob upload gear active. 05:29:52 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:29:52 INFO - Copying logs to upload dir... 05:29:52 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.144895 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-16 05:29:51.304479) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:29:51.307744) ========= 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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010036 build_url: 'https://queue.taskcluster.net/v1/task/8zsiQ59tTfeqLN2EOOfFGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:29:51.359028) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:29:51.359412) ========= 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=0cff312cb257a7829adc5a5d000001df-1450271323.766125-1065299473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004502 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:29:51.409551) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 05:29:51.409899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 05:29:51.410278) ========= ========= Total master_lag: 0.21 =========