builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-053 starttime: 1450094913.23 results: success (0) buildid: 20151214025632 builduid: b66ad645f9ea46ce986289e656b5de0c revision: 99137d6d4061f408ae0869122649d8bdf489cc30 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.234753) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.235170) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.235483) ========= 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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006443 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.269584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.270009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.327659) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.327951) ========= 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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004531 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.382585) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.382857) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.383195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.383491) ========= 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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-14 04:08:35-- 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% 210M=0s 2015-12-14 04:08:36 (210 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532641 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.937174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.937492) ========= 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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017139 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.988266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:33.988611) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 99137d6d4061f408ae0869122649d8bdf489cc30 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 99137d6d4061f408ae0869122649d8bdf489cc30 --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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-14 04:08:36,170 truncating revision to first 12 chars 2015-12-14 04:08:36,171 Setting DEBUG logging. 2015-12-14 04:08:36,171 attempt 1/10 2015-12-14 04:08:36,171 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/99137d6d4061?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-14 04:08:36,568 unpacking tar archive at: mozilla-central-99137d6d4061/testing/mozharness/ program finished with exit code 0 elapsedTime=0.711652 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:34.712967) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:34.713278) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:34.720513) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:34.720781) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-14 04:08:34.721207) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-12-14 04:08:34.721513) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:08:36 INFO - MultiFileLogger online at 20151214 04:08:36 in /builds/slave/test 04:08:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:08:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:36 INFO - {'append_to_log': False, 04:08:36 INFO - 'base_work_dir': '/builds/slave/test', 04:08:36 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:08:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:36 INFO - 'branch': 'Firefox-Non-PGO', 04:08:36 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:36 INFO - 'config_files': ('talos/linux_config.py',), 04:08:36 INFO - 'default_actions': ('clobber', 04:08:36 INFO - 'read-buildbot-config', 04:08:36 INFO - 'download-and-extract', 04:08:36 INFO - 'populate-webroot', 04:08:36 INFO - 'create-virtualenv', 04:08:36 INFO - 'install', 04:08:36 INFO - 'run-tests'), 04:08:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:36 INFO - 'download_minidump_stackwalk': True, 04:08:36 INFO - 'download_symbols': 'ondemand', 04:08:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:08:36 INFO - 'tooltool.py': '/tools/tooltool.py', 04:08:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:08:36 INFO - '/tools/misc-python/virtualenv.py')}, 04:08:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:36 INFO - 'installer_path': 'installer.exe', 04:08:36 INFO - 'log_level': 'info', 04:08:36 INFO - 'log_name': 'talos', 04:08:36 INFO - 'log_to_console': True, 04:08:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:08:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:08:36 INFO - 'opt_config_files': (), 04:08:36 INFO - 'pip_index': False, 04:08:36 INFO - 'sps_profile': False, 04:08:36 INFO - 'sps_profile_interval': 0, 04:08:36 INFO - 'suite': 'dromaeojs-e10s', 04:08:36 INFO - 'system_bits': '64', 04:08:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:36 INFO - 'title': 'talos-linux64-ix-053', 04:08:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:36 INFO - 'use_talos_json': True, 04:08:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:36 INFO - 'work_dir': 'build'} 04:08:36 INFO - ##### 04:08:36 INFO - ##### Running clobber step. 04:08:36 INFO - ##### 04:08:36 INFO - Running pre-action listener: _resource_record_pre_action 04:08:36 INFO - Running main action method: clobber 04:08:36 INFO - rmtree: /builds/slave/test/build 04:08:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:37 INFO - Running post-action listener: _resource_record_post_action 04:08:37 INFO - ##### 04:08:37 INFO - ##### Running read-buildbot-config step. 04:08:37 INFO - ##### 04:08:37 INFO - Running pre-action listener: _resource_record_pre_action 04:08:37 INFO - Running main action method: read_buildbot_config 04:08:37 INFO - Using buildbot properties: 04:08:37 INFO - { 04:08:37 INFO - "properties": { 04:08:37 INFO - "buildnumber": 120, 04:08:37 INFO - "product": "firefox", 04:08:37 INFO - "script_repo_revision": "production", 04:08:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:08:37 INFO - "repository": "", 04:08:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:08:37 INFO - "buildid": "20151214025632", 04:08:37 INFO - "slavename": "talos-linux64-ix-053", 04:08:37 INFO - "pgo_build": "False", 04:08:37 INFO - "basedir": "/builds/slave/test", 04:08:37 INFO - "project": "", 04:08:37 INFO - "platform": "ubuntu64_hw", 04:08:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:08:37 INFO - "slavebuilddir": "test", 04:08:37 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:08:37 INFO - "branch": "mozilla-central", 04:08:37 INFO - "repo_path": "mozilla-central", 04:08:37 INFO - "stage_platform": "linux64", 04:08:37 INFO - "builduid": "b66ad645f9ea46ce986289e656b5de0c", 04:08:37 INFO - "revision": "99137d6d4061f408ae0869122649d8bdf489cc30" 04:08:37 INFO - }, 04:08:37 INFO - "sourcestamp": { 04:08:37 INFO - "repository": "", 04:08:37 INFO - "hasPatch": false, 04:08:37 INFO - "project": "", 04:08:37 INFO - "branch": "mozilla-central-linux64-talos", 04:08:37 INFO - "changes": [ 04:08:37 INFO - { 04:08:37 INFO - "category": null, 04:08:37 INFO - "files": [ 04:08:37 INFO - { 04:08:37 INFO - "url": null, 04:08:37 INFO - "name": "https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 04:08:37 INFO - } 04:08:37 INFO - ], 04:08:37 INFO - "repository": "", 04:08:37 INFO - "rev": "99137d6d4061f408ae0869122649d8bdf489cc30", 04:08:37 INFO - "who": "cbook@mozilla.com", 04:08:37 INFO - "when": 1450094876, 04:08:37 INFO - "number": 7357083, 04:08:37 INFO - "comments": "Merge m-c to b-i", 04:08:37 INFO - "project": "", 04:08:37 INFO - "at": "Mon 14 Dec 2015 04:07:56", 04:08:37 INFO - "branch": "mozilla-central-linux64-talos", 04:08:37 INFO - "revlink": "", 04:08:37 INFO - "properties": [ 04:08:37 INFO - [ 04:08:37 INFO - "buildid", 04:08:37 INFO - "20151214025632", 04:08:37 INFO - "Change" 04:08:37 INFO - ], 04:08:37 INFO - [ 04:08:37 INFO - "builduid", 04:08:37 INFO - "b66ad645f9ea46ce986289e656b5de0c", 04:08:37 INFO - "Change" 04:08:37 INFO - ], 04:08:37 INFO - [ 04:08:37 INFO - "pgo_build", 04:08:37 INFO - "False", 04:08:37 INFO - "Change" 04:08:37 INFO - ] 04:08:37 INFO - ], 04:08:37 INFO - "revision": "99137d6d4061f408ae0869122649d8bdf489cc30" 04:08:37 INFO - } 04:08:37 INFO - ], 04:08:37 INFO - "revision": "99137d6d4061f408ae0869122649d8bdf489cc30" 04:08:37 INFO - } 04:08:37 INFO - } 04:08:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 04:08:37 INFO - Running post-action listener: _resource_record_post_action 04:08:37 INFO - ##### 04:08:37 INFO - ##### Running download-and-extract step. 04:08:37 INFO - ##### 04:08:37 INFO - Running pre-action listener: _resource_record_pre_action 04:08:37 INFO - Running main action method: download_and_extract 04:08:37 INFO - mkdir: /builds/slave/test/build/tests 04:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:37 INFO - https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:08:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/test_packages.json 04:08:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/test_packages.json 04:08:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:08:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:08:38 INFO - Downloaded 1302 bytes. 04:08:38 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:08:38 INFO - Using the following test package requirements: 04:08:38 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:08:38 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:08:38 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'jsshell-linux-x86_64.zip'], 04:08:38 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:08:38 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:08:38 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:08:38 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:08:38 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:08:38 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:08:38 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:08:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:08:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:38 INFO - https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:39 INFO - Downloaded 21869728 bytes. 04:08:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:08:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:08:40 INFO - Return code: 0 04:08:40 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:08:40 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')]} 04:08:40 INFO - https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:43 INFO - Downloaded 21869728 bytes. 04:08:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:08:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:08:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:08:44 INFO - Return code: 0 04:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:44 INFO - https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:08:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:08:45 INFO - Downloaded 11132038 bytes. 04:08:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:08:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:08:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:08:45 INFO - Return code: 0 04:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:45 INFO - https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:08:46 INFO - Downloaded 57739813 bytes. 04:08:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:08:46 INFO - mkdir: /builds/slave/test/properties 04:08:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:08:46 INFO - Writing to file /builds/slave/test/properties/build_url 04:08:46 INFO - Contents: 04:08:46 INFO - build_url:https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:08:46 INFO - Running post-action listener: _resource_record_post_action 04:08:46 INFO - Running post-action listener: set_extra_try_arguments 04:08:46 INFO - ##### 04:08:46 INFO - ##### Running populate-webroot step. 04:08:46 INFO - ##### 04:08:46 INFO - Running pre-action listener: _resource_record_pre_action 04:08:46 INFO - Running main action method: populate_webroot 04:08:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:08:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:08:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:08:46 INFO - '--tppagecycles', 04:08:46 INFO - '7'], 04:08:46 INFO - 'tests': ['tsvgm']}}, 04:08:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:08:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:08:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:08:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:08:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'tests': ['damp', 'tps']}, 04:08:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['damp', 'tps']}, 04:08:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:08:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:08:46 INFO - 'other': {'tests': ['a11yr', 04:08:46 INFO - 'ts_paint', 04:08:46 INFO - 'tpaint', 04:08:46 INFO - 'sessionrestore', 04:08:46 INFO - 'sessionrestore_no_auto_restore']}, 04:08:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['a11yr', 04:08:46 INFO - 'ts_paint', 04:08:46 INFO - 'tpaint', 04:08:46 INFO - 'sessionrestore', 04:08:46 INFO - 'sessionrestore_no_auto_restore']}, 04:08:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['a11yr', 04:08:46 INFO - 'ts_paint', 04:08:46 INFO - 'tpaint', 04:08:46 INFO - 'sessionrestore', 04:08:46 INFO - 'sessionrestore_no_auto_restore']}, 04:08:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['a11yr', 04:08:46 INFO - 'ts_paint', 04:08:46 INFO - 'tpaint', 04:08:46 INFO - 'sessionrestore', 04:08:46 INFO - 'sessionrestore_no_auto_restore']}, 04:08:46 INFO - 'other_l64': {'tests': ['a11yr', 04:08:46 INFO - 'ts_paint', 04:08:46 INFO - 'tpaint', 04:08:46 INFO - 'sessionrestore', 04:08:46 INFO - 'sessionrestore_no_auto_restore']}, 04:08:46 INFO - 'other_nol64': {'tests': ['a11yr', 04:08:46 INFO - 'ts_paint', 04:08:46 INFO - 'tpaint', 04:08:46 INFO - 'sessionrestore', 04:08:46 INFO - 'sessionrestore_no_auto_restore']}, 04:08:46 INFO - 'svgr': {'tests': ['tsvgx', 04:08:46 INFO - 'tsvgr_opacity', 04:08:46 INFO - 'tart', 04:08:46 INFO - 'tscrollx', 04:08:46 INFO - 'cart']}, 04:08:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['tsvgx', 04:08:46 INFO - 'tsvgr_opacity', 04:08:46 INFO - 'tart', 04:08:46 INFO - 'tscrollx', 04:08:46 INFO - 'cart']}, 04:08:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'tests': ['tp5o']}, 04:08:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'talos_options': ['--e10s'], 04:08:46 INFO - 'tests': ['tp5o']}, 04:08:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'talos_options': ['--xperf_path', 04:08:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:46 INFO - 'tests': ['tp5n']}, 04:08:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:46 INFO - 'talos_options': ['--e10s', 04:08:46 INFO - '--xperf_path', 04:08:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:46 INFO - 'tests': ['tp5n']}}, 04:08:46 INFO - 'talos.zip': {'path': '', 04:08:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:08:46 INFO - Running post-action listener: _resource_record_post_action 04:08:46 INFO - ##### 04:08:46 INFO - ##### Running create-virtualenv step. 04:08:46 INFO - ##### 04:08:46 INFO - Running pre-action listener: _resource_record_pre_action 04:08:46 INFO - Running main action method: create_virtualenv 04:08:46 INFO - Creating virtualenv /builds/slave/test/build/venv 04:08:46 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 04:08:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:08:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:08:47 INFO - Using real prefix '/usr' 04:08:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:08:47 INFO - Installing distribute.............................................................................................................................................................................................done. 04:08:49 INFO - Installing pip.................done. 04:08:49 INFO - Return code: 0 04:08:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:49 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:08:49 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 04:08:49 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 04:08:49 INFO - Using env: {'DISPLAY': ':0', 04:08:49 INFO - 'HOME': '/home/cltbld', 04:08:49 INFO - 'LANG': 'en_US.UTF-8', 04:08:49 INFO - 'LANGUAGE': 'en_US:en', 04:08:49 INFO - 'LOGNAME': 'cltbld', 04:08:49 INFO - 'MAIL': '/var/mail/cltbld', 04:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:49 INFO - 'MOZ_NO_REMOTE': '1', 04:08:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:49 INFO - 'NO_EM_RESTART': '1', 04:08:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:49 INFO - 'PWD': '/builds/slave/test', 04:08:49 INFO - 'SHELL': '/bin/bash', 04:08:49 INFO - 'SHLVL': '1', 04:08:49 INFO - 'TERM': 'linux', 04:08:49 INFO - 'TMOUT': '86400', 04:08:49 INFO - 'USER': 'cltbld', 04:08:49 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:49 INFO - '_': '/tools/buildbot/bin/python'} 04:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:49 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 04:08:49 INFO - Cleaning up... 04:08:49 INFO - Return code: 0 04:08:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:49 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:08:49 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 04:08:49 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 04:08:49 INFO - Using env: {'DISPLAY': ':0', 04:08:49 INFO - 'HOME': '/home/cltbld', 04:08:49 INFO - 'LANG': 'en_US.UTF-8', 04:08:49 INFO - 'LANGUAGE': 'en_US:en', 04:08:49 INFO - 'LOGNAME': 'cltbld', 04:08:49 INFO - 'MAIL': '/var/mail/cltbld', 04:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:49 INFO - 'MOZ_NO_REMOTE': '1', 04:08:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:49 INFO - 'NO_EM_RESTART': '1', 04:08:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:49 INFO - 'PWD': '/builds/slave/test', 04:08:49 INFO - 'SHELL': '/bin/bash', 04:08:49 INFO - 'SHLVL': '1', 04:08:49 INFO - 'TERM': 'linux', 04:08:49 INFO - 'TMOUT': '86400', 04:08:49 INFO - 'USER': 'cltbld', 04:08:49 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:49 INFO - '_': '/tools/buildbot/bin/python'} 04:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:49 INFO - Downloading/unpacking psutil>=0.7.1 04:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:51 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:08:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:08:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:51 INFO - Installing collected packages: psutil 04:08:51 INFO - Running setup.py install for psutil 04:08:51 INFO - building 'psutil._psutil_linux' extension 04:08:51 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 04:08:52 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 04:08:52 INFO - building 'psutil._psutil_posix' extension 04:08:52 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 04:08:52 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 04:08:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:52 INFO - Successfully installed psutil 04:08:52 INFO - Cleaning up... 04:08:52 INFO - Return code: 0 04:08:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04: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')]} 04:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:52 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:08:52 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 04:08:52 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 04:08:52 INFO - Using env: {'DISPLAY': ':0', 04:08:52 INFO - 'HOME': '/home/cltbld', 04:08:52 INFO - 'LANG': 'en_US.UTF-8', 04:08:52 INFO - 'LANGUAGE': 'en_US:en', 04:08:52 INFO - 'LOGNAME': 'cltbld', 04:08:52 INFO - 'MAIL': '/var/mail/cltbld', 04:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:52 INFO - 'MOZ_NO_REMOTE': '1', 04:08:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:52 INFO - 'NO_EM_RESTART': '1', 04:08:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:52 INFO - 'PWD': '/builds/slave/test', 04:08:52 INFO - 'SHELL': '/bin/bash', 04:08:52 INFO - 'SHLVL': '1', 04:08:52 INFO - 'TERM': 'linux', 04:08:52 INFO - 'TMOUT': '86400', 04:08:52 INFO - 'USER': 'cltbld', 04:08:52 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:52 INFO - '_': '/tools/buildbot/bin/python'} 04:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:08:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:08:54 INFO - Installing collected packages: mozsystemmonitor 04:08:54 INFO - Running setup.py install for mozsystemmonitor 04:08:54 INFO - Successfully installed mozsystemmonitor 04:08:54 INFO - Cleaning up... 04:08:54 INFO - Return code: 0 04:08:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:08:54 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')]} 04:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:54 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')]} 04:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:54 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:08:54 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 04:08:54 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 04:08:54 INFO - Using env: {'DISPLAY': ':0', 04:08:54 INFO - 'HOME': '/home/cltbld', 04:08:54 INFO - 'LANG': 'en_US.UTF-8', 04:08:54 INFO - 'LANGUAGE': 'en_US:en', 04:08:54 INFO - 'LOGNAME': 'cltbld', 04:08:54 INFO - 'MAIL': '/var/mail/cltbld', 04:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:54 INFO - 'MOZ_NO_REMOTE': '1', 04:08:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:54 INFO - 'NO_EM_RESTART': '1', 04:08:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:54 INFO - 'PWD': '/builds/slave/test', 04:08:54 INFO - 'SHELL': '/bin/bash', 04:08:54 INFO - 'SHLVL': '1', 04:08:54 INFO - 'TERM': 'linux', 04:08:54 INFO - 'TMOUT': '86400', 04:08:54 INFO - 'USER': 'cltbld', 04:08:54 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:54 INFO - '_': '/tools/buildbot/bin/python'} 04:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:54 INFO - Downloading/unpacking blobuploader==1.2.4 04:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:56 INFO - Downloading blobuploader-1.2.4.tar.gz 04:08:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:08:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:08:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:56 INFO - Downloading docopt-0.6.1.tar.gz 04:08:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:08:56 INFO - Installing collected packages: blobuploader, requests, docopt 04:08:56 INFO - Running setup.py install for blobuploader 04:08:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:08:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:08:57 INFO - Running setup.py install for requests 04:08:57 INFO - Running setup.py install for docopt 04:08:57 INFO - Successfully installed blobuploader requests docopt 04:08:57 INFO - Cleaning up... 04:08:57 INFO - Return code: 0 04:08:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04: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')]} 04:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:57 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:08:57 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 04:08:57 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 04:08:57 INFO - Using env: {'DISPLAY': ':0', 04:08:57 INFO - 'HOME': '/home/cltbld', 04:08:57 INFO - 'LANG': 'en_US.UTF-8', 04:08:57 INFO - 'LANGUAGE': 'en_US:en', 04:08:57 INFO - 'LOGNAME': 'cltbld', 04:08:57 INFO - 'MAIL': '/var/mail/cltbld', 04:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:57 INFO - 'MOZ_NO_REMOTE': '1', 04:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:08:57 INFO - 'NO_EM_RESTART': '1', 04:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:57 INFO - 'PWD': '/builds/slave/test', 04:08:57 INFO - 'SHELL': '/bin/bash', 04:08:57 INFO - 'SHLVL': '1', 04:08:57 INFO - 'TERM': 'linux', 04:08:57 INFO - 'TMOUT': '86400', 04:08:57 INFO - 'USER': 'cltbld', 04:08:57 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:57 INFO - '_': '/tools/buildbot/bin/python'} 04:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:57 INFO - Running setup.py (path:/tmp/pip-kBYvPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:57 INFO - Running setup.py (path:/tmp/pip-vzcn7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:57 INFO - Running setup.py (path:/tmp/pip-K_UPo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:58 INFO - Running setup.py (path:/tmp/pip-Z71hHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:58 INFO - Running setup.py (path:/tmp/pip-ZYOZ3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:58 INFO - Running setup.py (path:/tmp/pip-yehH31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:58 INFO - Running setup.py (path:/tmp/pip-hbdaU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:58 INFO - Running setup.py (path:/tmp/pip-a5VFdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:58 INFO - Running setup.py (path:/tmp/pip-lHYfCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:58 INFO - Running setup.py (path:/tmp/pip-iUfm_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:58 INFO - Running setup.py (path:/tmp/pip-RUHqrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:58 INFO - Running setup.py (path:/tmp/pip-pDJsvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:58 INFO - Running setup.py (path:/tmp/pip-ywDmMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:58 INFO - Running setup.py (path:/tmp/pip-lScbKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:58 INFO - Running setup.py (path:/tmp/pip-u5VUWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:58 INFO - Running setup.py (path:/tmp/pip-V2eAUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:58 INFO - Running setup.py (path:/tmp/pip-UCFh1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:08:58 INFO - Running setup.py install for manifestparser 04:08:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Running setup.py install for mozcrash 04:08:59 INFO - Running setup.py install for mozdebug 04:08:59 INFO - Running setup.py install for mozdevice 04:08:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Running setup.py install for mozfile 04:08:59 INFO - Running setup.py install for mozhttpd 04:08:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Running setup.py install for mozinfo 04:08:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Running setup.py install for mozInstall 04:08:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:08:59 INFO - Running setup.py install for mozleak 04:08:59 INFO - Running setup.py install for mozlog 04:08:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Running setup.py install for moznetwork 04:09:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Running setup.py install for mozprocess 04:09:00 INFO - Running setup.py install for mozprofile 04:09:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Running setup.py install for mozrunner 04:09:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Running setup.py install for mozscreenshot 04:09:00 INFO - Running setup.py install for moztest 04:09:00 INFO - Running setup.py install for mozversion 04:09:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:09:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:09:00 INFO - Cleaning up... 04:09:00 INFO - Return code: 0 04:09:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:00 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:09:00 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 04:09:00 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 04:09:00 INFO - Using env: {'DISPLAY': ':0', 04:09:00 INFO - 'HOME': '/home/cltbld', 04:09:00 INFO - 'LANG': 'en_US.UTF-8', 04:09:00 INFO - 'LANGUAGE': 'en_US:en', 04:09:00 INFO - 'LOGNAME': 'cltbld', 04:09:00 INFO - 'MAIL': '/var/mail/cltbld', 04:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:00 INFO - 'MOZ_NO_REMOTE': '1', 04:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:00 INFO - 'NO_EM_RESTART': '1', 04:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:00 INFO - 'PWD': '/builds/slave/test', 04:09:00 INFO - 'SHELL': '/bin/bash', 04:09:00 INFO - 'SHLVL': '1', 04:09:00 INFO - 'TERM': 'linux', 04:09:00 INFO - 'TMOUT': '86400', 04:09:00 INFO - 'USER': 'cltbld', 04:09:00 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:00 INFO - '_': '/tools/buildbot/bin/python'} 04:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:00 INFO - Running setup.py (path:/tmp/pip-kj1yss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:01 INFO - Running setup.py (path:/tmp/pip-hRjFgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:01 INFO - Running setup.py (path:/tmp/pip-iibBuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:01 INFO - Running setup.py (path:/tmp/pip-9_V8Cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:01 INFO - Running setup.py (path:/tmp/pip-i0xRNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:01 INFO - Running setup.py (path:/tmp/pip-Moq2vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:01 INFO - Running setup.py (path:/tmp/pip-wpzYbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:01 INFO - Running setup.py (path:/tmp/pip-AQa73i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:01 INFO - Running setup.py (path:/tmp/pip-rLGRo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:01 INFO - Running setup.py (path:/tmp/pip-pBNjqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:01 INFO - Running setup.py (path:/tmp/pip-CVnEDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:01 INFO - Running setup.py (path:/tmp/pip-phYSfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:01 INFO - Running setup.py (path:/tmp/pip-R4zVcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:01 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)) 04:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:01 INFO - Running setup.py (path:/tmp/pip-34o3ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:02 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)) 04:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:02 INFO - Running setup.py (path:/tmp/pip-PHPeaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:02 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)) 04:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:02 INFO - Running setup.py (path:/tmp/pip-QfF7RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:02 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)) 04:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:02 INFO - Running setup.py (path:/tmp/pip-cBRmeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:02 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)) 04:09:02 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)) 04:09:02 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)) 04:09:02 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)) 04:09:02 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)) 04:09:02 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)) 04:09:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:02 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 04:09:02 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 04:09:02 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 04:09:02 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 04:09:02 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 04:09:02 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 04:09:03 INFO - Downloading blessings-1.5.1.tar.gz 04:09:03 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 04:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:09:04 INFO - Installing collected packages: blessings 04:09:04 INFO - Running setup.py install for blessings 04:09:04 INFO - Successfully installed blessings 04:09:04 INFO - Cleaning up... 04:09:04 INFO - Return code: 0 04:09:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:04 INFO - Reading from file tmpfile_stdout 04:09:04 INFO - Current package versions: 04:09:04 INFO - argparse == 1.2.1 04:09:04 INFO - blessings == 1.5.1 04:09:04 INFO - blobuploader == 1.2.4 04:09:04 INFO - docopt == 0.6.1 04:09:04 INFO - manifestparser == 1.1 04:09:04 INFO - mozInstall == 1.12 04:09:04 INFO - mozcrash == 0.16 04:09:04 INFO - mozdebug == 0.1 04:09:04 INFO - mozdevice == 0.47 04:09:04 INFO - mozfile == 1.2 04:09:04 INFO - mozhttpd == 0.7 04:09:04 INFO - mozinfo == 0.9 04:09:04 INFO - mozleak == 0.1 04:09:04 INFO - mozlog == 3.1 04:09:04 INFO - moznetwork == 0.27 04:09:04 INFO - mozprocess == 0.22 04:09:04 INFO - mozprofile == 0.27 04:09:04 INFO - mozrunner == 6.11 04:09:04 INFO - mozscreenshot == 0.1 04:09:04 INFO - mozsystemmonitor == 0.0 04:09:04 INFO - moztest == 0.7 04:09:04 INFO - mozversion == 1.4 04:09:04 INFO - psutil == 3.1.1 04:09:04 INFO - requests == 1.2.3 04:09:04 INFO - wsgiref == 0.1.2 04:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04: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')]} 04:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:04 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:09:04 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 04:09:04 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 04:09:04 INFO - Using env: {'DISPLAY': ':0', 04:09:04 INFO - 'HOME': '/home/cltbld', 04:09:04 INFO - 'LANG': 'en_US.UTF-8', 04:09:04 INFO - 'LANGUAGE': 'en_US:en', 04:09:04 INFO - 'LOGNAME': 'cltbld', 04:09:04 INFO - 'MAIL': '/var/mail/cltbld', 04:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:04 INFO - 'MOZ_NO_REMOTE': '1', 04:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:04 INFO - 'NO_EM_RESTART': '1', 04:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:04 INFO - 'PWD': '/builds/slave/test', 04:09:04 INFO - 'SHELL': '/bin/bash', 04:09:04 INFO - 'SHLVL': '1', 04:09:04 INFO - 'TERM': 'linux', 04:09:04 INFO - 'TMOUT': '86400', 04:09:04 INFO - 'USER': 'cltbld', 04:09:04 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:04 INFO - '_': '/tools/buildbot/bin/python'} 04:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:09:04 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)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:09:04 INFO - Cleaning up... 04:09:04 INFO - Return code: 0 04:09:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04: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')]} 04:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:04 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:09:04 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 04:09:04 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 04:09:04 INFO - Using env: {'DISPLAY': ':0', 04:09:04 INFO - 'HOME': '/home/cltbld', 04:09:04 INFO - 'LANG': 'en_US.UTF-8', 04:09:04 INFO - 'LANGUAGE': 'en_US:en', 04:09:04 INFO - 'LOGNAME': 'cltbld', 04:09:04 INFO - 'MAIL': '/var/mail/cltbld', 04:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:04 INFO - 'MOZ_NO_REMOTE': '1', 04:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:04 INFO - 'NO_EM_RESTART': '1', 04:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:04 INFO - 'PWD': '/builds/slave/test', 04:09:04 INFO - 'SHELL': '/bin/bash', 04:09:04 INFO - 'SHLVL': '1', 04:09:04 INFO - 'TERM': 'linux', 04:09:04 INFO - 'TMOUT': '86400', 04:09:04 INFO - 'USER': 'cltbld', 04:09:04 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:04 INFO - '_': '/tools/buildbot/bin/python'} 04:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:04 INFO - Downloading/unpacking jsonschema 04: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 04: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 04: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 04: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 04: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 04: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 04:09:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:06 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 04:09:06 INFO - Downloading/unpacking functools32 (from jsonschema) 04:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:06 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:06 INFO - warning: no files found matching '*.txt' 04:09:06 INFO - no previously-included directories found matching 'build' 04:09:06 INFO - no previously-included directories found matching 'dist' 04:09:06 INFO - no previously-included directories found matching '.git*' 04:09:06 INFO - Installing collected packages: jsonschema, functools32 04:09:07 INFO - Running setup.py install for functools32 04:09:07 INFO - warning: no files found matching '*.txt' 04:09:07 INFO - no previously-included directories found matching 'build' 04:09:07 INFO - no previously-included directories found matching 'dist' 04:09:07 INFO - no previously-included directories found matching '.git*' 04:09:07 INFO - Successfully installed jsonschema functools32 04:09:07 INFO - Cleaning up... 04:09:07 INFO - Return code: 0 04:09:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:09:07 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')]} 04:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:07 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')]} 04:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:07 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 0x7f294f968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x176f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183d240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, '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': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', '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 04:09:07 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 04:09:07 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 04:09:07 INFO - Using env: {'DISPLAY': ':0', 04:09:07 INFO - 'HOME': '/home/cltbld', 04:09:07 INFO - 'LANG': 'en_US.UTF-8', 04:09:07 INFO - 'LANGUAGE': 'en_US:en', 04:09:07 INFO - 'LOGNAME': 'cltbld', 04:09:07 INFO - 'MAIL': '/var/mail/cltbld', 04:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:07 INFO - 'MOZ_NO_REMOTE': '1', 04:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:07 INFO - 'NO_EM_RESTART': '1', 04:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:07 INFO - 'PWD': '/builds/slave/test', 04:09:07 INFO - 'SHELL': '/bin/bash', 04:09:07 INFO - 'SHLVL': '1', 04:09:07 INFO - 'TERM': 'linux', 04:09:07 INFO - 'TMOUT': '86400', 04:09:07 INFO - 'USER': 'cltbld', 04:09:07 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:07 INFO - '_': '/tools/buildbot/bin/python'} 04:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:07 INFO - Downloading/unpacking flake8 04:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:09:09 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 04:09:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04: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 04: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 04: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 04: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 04: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 04: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 04:09:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:09:09 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 04:09:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04: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 04: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 04: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 04: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 04: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 04: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 04:09:09 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 04:09:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04: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 04: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 04: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 04: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 04: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 04: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 04:09:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:09:09 INFO - no previously-included directories found matching 'docs/_build' 04:09:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:09:09 INFO - Running setup.py install for pep8 04:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:09:09 INFO - no previously-included directories found matching 'docs/_build' 04:09:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:09:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:09:09 INFO - Cleaning up... 04:09:09 INFO - Return code: 0 04:09:09 INFO - Running post-action listener: _resource_record_post_action 04:09:09 INFO - Running post-action listener: _start_resource_monitoring 04:09:09 INFO - Starting resource monitoring. 04:09:09 INFO - ##### 04:09:09 INFO - ##### Running install step. 04:09:09 INFO - ##### 04:09:09 INFO - Running pre-action listener: _resource_record_pre_action 04:09:09 INFO - Running main action method: install 04:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:10 INFO - Reading from file tmpfile_stdout 04:09:10 INFO - Detecting whether we're running mozinstall >=1.0... 04:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:09:10 INFO - Reading from file tmpfile_stdout 04:09:10 INFO - Output received: 04:09:10 INFO - Usage: mozinstall [options] installer 04:09:10 INFO - Options: 04:09:10 INFO - -h, --help show this help message and exit 04:09:10 INFO - -d DEST, --destination=DEST 04:09:10 INFO - Directory to install application into. [default: 04:09:10 INFO - "/builds/slave/test"] 04:09:10 INFO - --app=APP Application being installed. [default: firefox] 04:09:10 INFO - mkdir: /builds/slave/test/build/application 04:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:09:24 INFO - Reading from file tmpfile_stdout 04:09:24 INFO - Output received: 04:09:24 INFO - /builds/slave/test/build/application/firefox/firefox 04:09:24 INFO - Running post-action listener: _resource_record_post_action 04:09:24 INFO - ##### 04:09:24 INFO - ##### Running run-tests step. 04:09:24 INFO - ##### 04:09:24 INFO - Running pre-action listener: _resource_record_pre_action 04:09:24 INFO - Running main action method: run_tests 04:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:24 INFO - Python 2.7.3 04:09:24 INFO - Return code: 0 04:09:24 INFO - grabbing minidump binary from tooltool 04:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cc0b0>, '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 0x18ccfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18cd440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:09:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:09:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:24 INFO - Return code: 0 04:09:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:09:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:09:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:09:25 INFO - Return code: 0 04:09:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost'] in /builds/slave/test/build 04:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost 04:09:25 INFO - Using env: {'DISPLAY': ':0', 04:09:25 INFO - 'HOME': '/home/cltbld', 04:09:25 INFO - 'LANG': 'en_US.UTF-8', 04:09:25 INFO - 'LANGUAGE': 'en_US:en', 04:09:25 INFO - 'LOGNAME': 'cltbld', 04:09:25 INFO - 'MAIL': '/var/mail/cltbld', 04:09:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:25 INFO - 'MOZ_NO_REMOTE': '1', 04:09:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:25 INFO - 'NO_EM_RESTART': '1', 04:09:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:25 INFO - 'PWD': '/builds/slave/test', 04:09:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:25 INFO - 'SHELL': '/bin/bash', 04:09:25 INFO - 'SHLVL': '1', 04:09:25 INFO - 'TERM': 'linux', 04:09:25 INFO - 'TMOUT': '86400', 04:09:25 INFO - 'USER': 'cltbld', 04:09:25 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027', 04:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:25 INFO - '_': '/tools/buildbot/bin/python'} 04:09:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost'] with output_timeout 3600 04:09:26 INFO - mozversion INFO | application_buildid: 20151214025632 04:09:26 INFO - mozversion INFO | application_changeset: 99137d6d4061f408ae0869122649d8bdf489cc30 04:09:26 INFO - mozversion INFO | application_display_name: Nightly 04:09:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:26 INFO - mozversion INFO | application_name: Firefox 04:09:26 INFO - mozversion INFO | application_remotingname: firefox 04:09:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:09:26 INFO - mozversion INFO | application_vendor: Mozilla 04:09:26 INFO - mozversion INFO | application_version: 45.0a1 04:09:26 INFO - mozversion INFO | platform_buildid: 20151214025632 04:09:26 INFO - mozversion INFO | platform_changeset: 99137d6d4061f408ae0869122649d8bdf489cc30 04:09:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:09:26 INFO - mozversion INFO | platform_version: 45.0a1 04:09:26 INFO - 2015-12-14 04:09:26,030 DEBUG : using testdate: 1450094966 04:09:26 INFO - 2015-12-14 04:09:26,030 DEBUG : actual date: 1450094966 04:09:26 INFO - 2015-12-14 04:09:26,039 INFO : starting webserver on 'localhost:48964' 04:09:26 INFO - 2015-12-14 04:09:26,040 INFO : Starting test suite talos-linux64-ix-053.e 04:09:26 INFO - 2015-12-14 04:09:26,040 INFO : Starting test dromaeo_css 04:09:26 INFO - 2015-12-14 04:09:26,041 DEBUG : operating with platform_type : linux_ 04:09:26 INFO - 2015-12-14 04:09:26,041 INFO : Initialising browser for dromaeo_css test... 04:09:26 INFO - 2015-12-14 04:09:26,055 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF3aDyL/profile http://localhost:48964/getInfo.html 04:09:30 INFO - 2015-12-14 04:09:30,251 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:09:30 INFO - 2015-12-14 04:09:30,251 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:09:30 INFO - 2015-12-14 04:09:30,251 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:09:30 INFO - 2015-12-14 04:09:30,252 DEBUG : BROWSER_OUTPUT: __metrics 04:09:30 INFO - 2015-12-14 04:09:30,376 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] 04:09:31 INFO - 2015-12-14 04:09:31,245 INFO : Browser initialized. 04:09:31 INFO - 2015-12-14 04:09:31,246 INFO : Running cycle 1/1 for dromaeo_css test... 04:09:31 INFO - 2015-12-14 04:09:31,246 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF3aDyL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:09:43 INFO - 2015-12-14 04:09:43,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 143769600 04:09:43 INFO - 2015-12-14 04:09:43,263 DEBUG : BROWSER_OUTPUT: 04:10:32 INFO - 2015-12-14 04:10:32,880 DEBUG : BROWSER_OUTPUT: 1450095032880 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] 04:12:45 INFO - 2015-12-14 04:12:45,111 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48964/tests/dromaeo/cssquery-ext.html) 04:12:46 INFO - 2015-12-14 04:12:46,216 DEBUG : BROWSER_OUTPUT: RSS: Main: 147251200 04:12:46 INFO - 2015-12-14 04:12:46,216 DEBUG : BROWSER_OUTPUT: 04:15:48 INFO - 2015-12-14 04:15:48,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/dromaeo/cssquery-ext.html (next: http://localhost:48964/tests/dromaeo/cssquery-jquery.html) 04:15:48 INFO - 2015-12-14 04:15:48,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 145530880 04:15:48 INFO - 2015-12-14 04:15:48,845 DEBUG : BROWSER_OUTPUT: 04:18:50 INFO - 2015-12-14 04:18:50,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48964/tests/dromaeo/cssquery-mootools.html) 04:18:51 INFO - 2015-12-14 04:18:51,283 DEBUG : BROWSER_OUTPUT: RSS: Main: 145702912 04:18:51 INFO - 2015-12-14 04:18:51,283 DEBUG : BROWSER_OUTPUT: 04:21:53 INFO - 2015-12-14 04:21:53,032 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48964/tests/dromaeo/cssquery-prototype.html) 04:21:53 INFO - 2015-12-14 04:21:53,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 144814080 04:21:53 INFO - 2015-12-14 04:21:53,634 DEBUG : BROWSER_OUTPUT: 04:24:56 INFO - 2015-12-14 04:24:56,325 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48964/tests/dromaeo/cssquery-yui.html) 04:24:56 INFO - 2015-12-14 04:24:56,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 144576512 04:24:56 INFO - 2015-12-14 04:24:56,955 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/dromaeo/cssquery-yui.html (next: http://localhost:48964/tests/dromaeo/cssquery-dojo.html) 04:27:59 INFO - 2015-12-14 04:27:59,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 147320832 04:27:59 INFO - 2015-12-14 04:27:59,584 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,585 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:27:59 INFO - 2015-12-14 04:27:59,586 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:27:59 INFO - 2015-12-14 04:27:59,586 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:27:59 INFO - 2015-12-14 04:27:59,586 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:27:59 INFO - 2015-12-14 04:27:59,589 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2676.2640273924676;2707.5532537131376;2770.168949315206;2784.4152727927135;2785.234060633324;4663.463701674308;4820.710757354561;4821.783019764106;4846.951530484694;4863.0760155570415;4687.086018226447;4703.435587729477;4761.047790441909;4816.060868130708;4831.613470922327;4387.210302145612;4469.798859051342;4518.367428559988;4540.250858608342;4574.885627859314;4319.82720691172;4498.707584007046;4508.458984921814;4508.571685689871;4610.815567377301;4328.350747387917;4356.215881141393;4412.933805992929;4419.977900110479;4497.820087196508;4392.714473559208;4426;4444.955550444486;4446.555344465528;4467.441569803775;4397.780110994438;4428.247197976351;4433.689641725081;4475.59719625232;4501.842435514774;4502.324651302298;4568;4634.073185362941;4774.689645173053;4816.614670826326;5147.7683504242395;5167.896642067175;5168.198929165986;5181.766820493049;5238.371395432572;5003;5211.765470553834;5222.738863056832;5222.97388513055;5224.190250511177;4689.132510485571;4700.4594471635855;4708;4731.361266229015;4738.810447582127;5351.464853514617;5352.91970620441;5363.570914326844;5372.7313634318125;5450.945490545044;5102.642815002914;5120.974395128075;5123.462036486116;5129.281900533928;5170.793168273302;4845.75771211438;4960.851174464772;4966.453690094087;4970.527799859007;5253.36959564847;4939.382577177853;5008.223725322508;5032.144535428986;5127.179651255781;5149.871253218699;5094.77073531692;5101.948980510221;5102.744862756922;5110.361204849394;5148.665336753099;4285.078708077778;4297.441332626801;4310.181065597527;4311.0300182458695;4346.7826608670075;3646.361886669822;3674.7427680062674;3759.0414444316525;3762.1159027628487;3780.3951367781024;4510.774461276923;4535.319702044654;4549.476810166807;4552.271636538138;4608.654350923627;4958.826441074617;5247.921281180786;5248.816291429858;5257.237700533401;5264.499872512105;4437.755923424178;4474.418325617649;4494.123645889085;4498.527654596286;4548.090381923628;4592.862214133581;4602.953970460252;4626.560476754703;4628.305754136906;4634.97682511592;4709.764511774466;4892.926606100982;4964.528369804862;4967.304577359173;4978.626603004716;5067.290579318972;5107.923381149211;5141.177411614124;5142.460041695568;5153.433122356612;4920;4963.677360971526;4964.578010868972;4966.801327946841;4989.675671081368;4875.780589873535;4991.700497970133;4995.100881841304;5146.871328216675;5183.766730497061;5114.874727559931;5190.922136168036;5216.191490319006;5225.869353266199;5260.894782104413;5044.344235249394;5053.974730126326;5061.519155680205;5062.367204099488;5117.232415137759;4797.136515427256;4862.05189987949;4937.9012419752125;5055.620828437955;5056.115179843586;4927.162382394929;4934.111859865258;4983.700977941335;5012.8496145115705;5016;5121.308623335951;5124;5139.640225184201;5141.5629671476845;5167.069927413102;4833.637477189154;4840.34655321527;4948.802047918043;4950.579200767975;5008.949910500848 04:27:59 INFO - 2015-12-14 04:27:59,591 DEBUG : BROWSER_OUTPUT: |1;ext.html;557.8995780759462;564.9717514124293;566.3091028944687;574.9885002299955;585.833037584288;34741.30517389654;35425.29149417013;35515;35564.644353556454;35640;38925;39382.40926386107;39429.40855887164;39532.40701389474;39699.603003969954;7885.605719714005;8065.758027259191;8078.919210807875;8085.3531717462465;8088.636011379502;7882.645280962371;7994.64024118916;8006.319462845665;8007.919920800776;8016.63925123368;7761.3402860756905;7763.495372800779;7851;7865.842683146334;7876.173001834811;7886.053673559181;7890.763277101696;7945.6424460898825;8001.8799718004275;8025.357971362306;7816.140224575292;7900.762977110696;7940.325072368855;7945.086315073782;7962.960185199095;36540.26919461596;37145.44281835775;37158.44262336067;37185.07037324062;37194.62805371939;3069.785115041948;3084.4602194615854;3108.9689103108904;3332.166958260435;3462.4979377990303;28009.719902800916;28399.716002839912;28518.714812851813;28739.42521149586;28908.277293067633;3433.7424693147864;3439.1917899293644;3491.398653702319;3516.4197907345256;3539.256756081226;206542;209461.95269023709;209897;210000;210273;50326.49673503218;51595.22607160896;53809.1928621071;54160.72919635456;54442.727786361604;48353;48444.515554844;48525.75737121362;48591;48687.75656121768;12715;12930.870691292967;12970.740585188243;12975.286359250333;12978.935105324601;9720.854187187198;9739.123478886793;9749.122578967927;9793.167580755573;9827.606895724233;18552.165152568166;18655.813441865677;18711;18753.624927501645;18779.028202038862;7604.467687261949;7718.421118416141;7785.221477852169;7856.803579910548;7891.960540197377;880.6521424037469;906.1255888068046;911.6034524981521;948.9952550237205;961.9519024048909;26006.6099008515;26383.590644910284;26710.465790684077;26755.598666020025;26809.86595067051;992.7915137821138;1033.560736686905;1071.2180108520824;1075.44077079918;1124.2973141786383;13615.863841361459;13723.656908577166;14087.36606852694;14578.489752858599;14799.260036998106;255391;258345;259786;260134;260353;46790.5320946793;46988.76505617519;47023.764881175375;47239;47261.763691181324;1201.8377519034993;1226.2519862883635;1232.9260244385366;1241.1560139105495;1246.214884622682;26001.869990649924;26373.73626263713;26418.60372094382;26515.469690605692;26578.867105664347;42740.786296069564;44070;44189.77905110454;44322.556774433135;44390.778046110856;195249;196995;197832;197964;198830;29056.27359316034;29203.85398072996;29463.852680736458;29677.258068548457;29842.850785745934;34757.826210868785;35470.290594187456;37527.81236093802;37668.43497347487;38375.42436863505;32023.519647204845;32311.35377292394;32318.19204519906;33073.173170670925;33276.83361583177;32672.343635244317;32783.67216327806;32797.836010819796;33110.66889331076;33111.17222069467;32137.196570085936;32539;32619;32817.67182328146;32881;28728.13815585536;28860.7113928858;29488.705112949454;29612;29680.406391872475;22996.19513317026;23104.768952310933;23349.182778602666;23350;23394 04:27:59 INFO - 2015-12-14 04:27:59,592 DEBUG : BROWSER_OUTPUT: |2;jquery.html;337.82095489390633;338.08210707927987;340.7103961632611;342.28633299570396;342.98799542016036;14959.40162393503;15101.924490377547;16161.757573636405;16283;16789.41237056703;14832.184229867353;14870.47953321634;14874.702505949901;14956.850431495683;15251.237438128104;4418.116376724652;4642.3500709900645;4695.483496815347;4698.271767875967;4702.553257440538;4654.767261636922;4689.460712018127;4693.671442998992;4694.694844835092;4696.154692155411;3403.1662242750563;3516.6483351664756;4690.742009189493;4691.812327506913;5146.150885103953;5245.370555533323;5288.444713305105;5300.761465734051;5302.3106996090455;5320.76056577453;4960.057589058103;4978.427480839678;4979.10376132296;4999.450060493343;5096.362954630671;11506.424678766112;11789.292642441394;11792;14859.702805943929;19110.71333929992;1822.8359447649775;1888.9149988250563;1913.5885784556388;1928.3347245200384;1972.5561748606592;9074.36479446439;9276.489793061379;9344.392614480104;11768.764624707543;12374.195677281037;1745.7643218165513;1756.7277072053723;1762.1101343821417;1777.7022773375509;1797.128392729525;54060.45939540633;55165.1725224122;57187.42812571821;57515.13727294094;58309.708451457474;9591.568379422803;9678.419294842332;9682.612695492242;9725.61097556104;9762.609495620096;12051.337176455203;12130.75738485243;12163;12185.329806860707;12247.448864801105;6838.213605435439;7011.3339232772805;7087.291270872975;7096.254893236239;7143.678534465961;6665.366790154928;6698.296678848748;6718.428933540704;6777.762778302835;6777.830554236085;4231.830726770895;4256.808443620044;4262.360645903077;4289.506706728704;4294.377315289265;1786.2319202742685;1796.694561924476;1801.4505575799244;1817.318505560415;1837.366108692499;823.2878560045517;836.5482639374669;837.4347315561971;874.8775171475999;904.3533873280636;15079.924600376928;15414.306356213998;15851.207439627973;15895.761563576556;15895.920520397323;837.3719710217337;842.0568962761623;848.0713618587637;871.3987348729356;878.7715194049593;5590.91613625796;8949.865752013726;9047.411918225294;9143.49710765901;9276.814463710823;526459;530190;531790;532124;545748;37701.24597508089;37706;77374.22625773783;78724;82851.3429731402;444.37565220864735;456.38160292802775;457.93131030345717;458.8990422107131;459.2903963376591;9714.222862170867;9747.561359738687;9863.556139973574;9876.604935802774;10078.344907580984;34793.65206347904;36357.09107272339;36568.26863462769;36718.26563468769;37418.43872341971;47289.763551182026;47673;48063.75968120137;49345.25982110343;49501.257481138535;8772.91227087721;8851.513166775632;8871.379003469954;8892.866607000771;8969.10308969124;15865.920670396574;16071.67856642884;16474.341026358812;16606.667866642358;16902.746458803373;14019.158850469004;14060.08609440383;14469;14657.413703451743;14658.267086645412;13591.592252232449;13726.45094196221;13756.380962856896;13757.243351615758;13951.58145255644;14935.85064149388;14964.476243331866;15169.544913652608;15217.619559510658;15431.768523471932;9148.679796207101;9203.263738900738;9343;9349.766255843659;9352.205062569486;10809.891901081202;13907.165570065828;14262.500812471513;14455.204963727096;14510.564683059525 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1453.658390278285;1687.1901487286102;1741.8171092035336;1756.6311074674318;1767.602289484866;6135.754569817202;6378.904316435257;7065.364117229448;7200.027996220515;7307;6904.067950826637;7234.565926044441;7256;7290.052293201877;7317.707291708339;2069.0896005757454;2085.9165633374723;2089.3105275259204;2094.4240333908147;2108.947276318089;2105.0737675422843;2114.1332053857923;2121.840861935353;2159.36298791856;2164.6969424280614;2064.143380497092;2099.8110170084688;2111.4299139232476;2121.087932189158;2133.829293656504;2048.8565800393976;2072.8652637578584;2073.979260207409;2076.4289820299537;2079.3761871438583;2057.6399130152313;2090.6758254375463;2128.5742851429623;2140.2295173737443;2149.4518897681146;7234.638268086627;7275;7290.744823931137;7301.780946571611;7319.59742214178;4153.813078411479;4234.13200293939;4240.045989652334;4246.957530424687;4249.617534421886;2740.0409856550245;2743.5198840203084;2762.986185069062;2765.3224959884956;2766.170148955305;875.2822685397977;881.501447539186;889.5685592487638;909.8407778638777;926.1895841139003;9775.315727898978;9823.803523929553;9843.114119729258;9854.65508707199;9864.161546268575;3934.5278566572197;3950.802459876995;4009.839606415769;4009.979950100231;4016.196760647882;8447.155284471626;8487.363447741443;8489.872651910226;8490.575471226413;8513.531755753493;4244.236037513216;4261.083866968616;4262.488501379854;4280.143971205771;4288.056627541936;3310.6854848789326;3408.727301815849;3855.7108216883844;3870.283997460479;3874.9418758718643;3375.3586818504896;3430.708389786846;3440.690337869604;3460.688538031539;3467.3065386922717;1084.3710647824244;1094.677070264258;1097.286763603664;1103.5751235774285;1103.8178700514486;758.2758465665277;770.1528318849221;770.6532060572765;771.1247733822153;837.3175861672718;6697.062411262427;6716.395524402729;6731.2932142125355;6734.528582999143;6736.124303840567;410.8377191009594;418.7843260720731;419.51755481196255;422.1051371093302;431.5274774122331;5151.845444636667;5174.870628234325;5227.085260079471;5243.711595862188;5245.26566280721;8712.215900568852;8728.738137855747;8881.600327985256;8897.510636914902;9013.954930225307;8783.253423459077;8805.955970220237;8806.427582207136;8814.427062240933;8872.467651940904;6682.131322927989;6852.588844669435;6852.760153394607;6865.828354291183;6901.689423975933;6975.825604359931;7027.4729395294535;7027.7188912442925;7032.472564557781;7042.7887163385185;7838.608069596383;7856.7250146244605;7946.682132714858;8037.8392432152195;8061;7698.2686644768655;7843.058832939855;7897;7934.563599001879;8086.9191308086165;5251.36983561975;5518.944810552004;6320.62076275425;6409.519286053472;6498.805035848932;6781.660916954232;6811.761588344384;6861.759838405632;6865.725370985105;6876.621785801829;6364.681765911779;6574.8356291093105;6620.536562440583;6694.263631000488;6705.4635629150525;6134.9079763804475;6263.843403914939;6322.778702745566;6541.901871472027;6565.015247712881;6359.300476947439;6413.4869210463985;6414.871702565829;6419.390157934989;6425.518086143651;6095.908561371672;6100.664463454553;6144.508439324933;6232.127502149793;6299.11812346281;6182.505399567934;6215.440610345091;6236.875262494816;6239.812805615838;6257.2491301044165 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: |4;prototype.html;304.3532493451416;312.91551281154074;314.7324773942148;315.089391658108;318.2346456771464;5431.592630552709;5609.326880774308;5625.5218306444;5639.915401268984;5656.802011929578;5534.695591742453;5549.472800083996;5549.861253468665;5550.833474995747;5556.333240011194;4182.372644103393;4290.399344091829;4310.762908040057;4312.116016216683;4330.220560299153;4295.098029413827;4303.870883873473;4329.220740266751;4350.238708226064;4355.237833379162;4283.464566929134;4291.120320334339;4291.184674911772;4293.248681480744;4301.419308393374;4255.787210639455;4289.356596510533;4290.742555446652;4298.097399546099;4315.6763242756915;3656.4880916671686;3730.608286129944;3733.757305775116;3742.8315725792404;3757.755745876509;5316.415194328621;5445.210444485567;5448.91826622601;5453.427390124019;5464.781408743645;4921;4951.5048495150195;4953.23224900141;4960.454350021495;4962.6774259673;5925.377835327315;5978.133170690226;5982.312034116088;5995.610285331483;6002.249718785152;3977.9204415911518;4044.211378781139;4064.79676016201;4065.57311482296;4078.857239996586;6167.352427995085;6261.186045814061;6264.1543391642;6270.623762574214;6282.842928926813;4176.185643799491;4186.895327616773;4187.497500299984;4192.11965487245;4193.35003074524;4234.301340278819;4251.128518653669;4256.7445953242905;4259.787010649455;4269.146170765859;4726.172919739032;4862.440819305754;4883.902321953612;4899.681520701143;4908.067467181224;5933.3473317934995;5942.346341902387;5956.285245770535;5959.135925290895;5966.701664916737;1470.9705805883823;1494.8505149484965;1503.0981411153439;1508.057464084947;1508.7133444645483;1003.9909890559941;1013.9239557033252;1016.7000734783256;1029.5469993202967;1030.6341248856697;5198.376194856642;5220.686758794484;5220.92168617471;5231.790728370898;5249.291345668286;6127.142200091991;6139.600925939889;6148.231471066117;6190.969045154746;6192.938070619326;5203.9219411708855;5229.6339256251695;5234.738263086831;5239.29269548606;5248.685078895279;3263.853126609308;3322.833858307075;3349.8325083745717;3374.1395944034116;3381.847816848248;5753.626014309057;5894.2926848778425;5896.115582662549;5900.380460051719;5903.35063143054;6420.454261387835;6454.128692626529;6454.419102280817;6455.903161452582;6463.903041454382;5772.884542309131;5845.41545845412;5853.180554722342;5884.764609415661;5897.852553686193;6033;6193.318734939243;6225.31521532622;6241;6244.344343843831;6071.332153463027;6074.848128796815;6079.3312735598165;6079.510519922647;6082.905077086167;5840.386759390372;5867.031939729897;5884.882302353843;5895.380984996514;5896.233489646319;4133.152703695795;4151.066010147692;4156.605122513356;4166.0834616384345;4177.8328866846205;6060.333363329942;6190.969045154746;6192.473639740672;6197.163382943245;6213.00591905293;5912.201852749996;5975.671338076448;5976.312724036791;5983.790567330123;5985.551083668829;5910.7340169693325;5960.003398861257;5961.2846458425265;5964.731587078505;5976.462118409364;5691.373948865705;5830.067189249869;5840.153177789111;5874.089516125007;5874.735636896436;6032.1856549365275;6038.9396106038375;6044.788432404845;6047.365026672136;6052.303985041776;5880.705964701833;5909.379515151019;5916.05343145112;5917.733701983335;5922.200502932222 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: |5;yui.html;668.9498287628429;772.9227077292271;781.4764108047608;785.0344076785553;791.5290402210686;11689;11829.231099978517;11866.7626647467;11891.108166887485;11895.345755983428;10511.421871797049;11011.779764404708;11066.557337706503;11429.714257143554;11454.427278636056;775.4029397364027;798.1619299735283;799.2926260259683;800.2277801921144;800.4676889868231;778.4900889917113;782.3311069035977;782.8316911863948;784.7920301120208;787.016229712859;811.6509900742678;818.9549574773386;825.5707032343178;826.6982551368767;828.7762304177868;816.9714060007872;818.9303909167699;820.4216027700487;821.5686764448683;824.4352618456377;610.5512448350458;613.9478144357735;614.2996983438883;614.4193736918613;614.4408588184775;10587.682369528926;10614.787704245871;10625.787484250272;10649.893501064967;10669;9248.861267081;9476.573554190078;11060.612878549213;11129.777404451866;11266.54933802655;9286.396384235004;9293.58178881952;9515.762105947337;9673.322867399285;9817.950910245474;3239.983800081008;3282.5404443377947;3289.3256882339065;3289.5723555937816;3302.2239773653173;37248;38137.23725525474;38169;38241.23517529634;39688.20623587541;8920.598573064228;9066.410683305565;9178.21985131258;9199.4940278284;9271.490068046322;15996.360145594279;16073;16103.597410064842;16148.596285092732;16159.757603635955;6164.106204600308;6238.844028899314;6244.531660125418;6274.937250627527;6471.158749362553;3452.8964131075604;3489.354469423165;3551.449525323579;3559.181388280658;3604.3692353837973;2690.3274181454635;2708.404151086758;2709.4581083783323;2726.4138210284486;2753.022676949694;498.61357447978105;499.787590274132;503.84128999365083;506.12693104394623;510.5303121128571;458.69965597526146;472.15248628711544;586.7569065146841;620.0667994667996;633.0251412824291;7478.2147874473485;8040.437169398086;8369.121242269595;8454.61954212062;8758.255548278468;491.4839418610516;492.79795283933413;560.8878224355145;585.3911931591183;628.4155735166248;3788.905277368033;3985.2627263955683;4125.628693417607;4128.195001974646;4255.76593287366;49294.75352623214;49729.25406118911;49731;49786;49944;43600;43792.562074379486;44095.33856992148;44121.55878441239;44251.33622995658;37519.437208441894;39099.609003909594;39151.608483914795;39326.80336598299;39974.20051598952;8256.628451719567;8262.710805122033;8345.54099524532;8925.910740892508;8984.730458086267;30267.848660756554;30503.389932201677;31244.375112498077;31293.530597041517;32557;39081.80459097686;39358;39672;39878.60121398749;40103.79948100241;10289.382637041801;10382.636607718694;10531.473426328499;10629.8937010632;10671.039606435457;10763.300385474919;10976.890231097586;11067.391293478937;11175.385353805294;11640.650780476599;10037.849432258376;10601.1519078472;10827.566897324334;11069.944650276697;11078.279911805706;10521.368717876645;10640.84038739404;10668.30656007357;10715.732106697395;10729.248952573245;11031.22781405294;11191.160662950475;11198.16013798952;11213.383263920563;11251.943740281245;9098.863517047117;9151.816963660822;9230.215431688383;9471.431714097176;9494.430334179971;9030.413023153475;9058.773530661787;9117.817643647222;9270.768230794283;9287.34988550822 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,320 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: __startTimestamp1450096079585__endTimestamp 04:27:59 INFO - 2015-12-14 04:27:59,596 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4761.73 Median:4892.93 stddev:501.04 (10.2%) stddev-sans-first:475.11 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: Values: 2676.3 2707.6 2770.2 2784.4 2785.2 4663.5 4820.7 4821.8 4847.0 4863.1 4687.1 4703.4 4761.0 4816.1 4831.6 4387.2 4469.8 4518.4 4540.3 4574.9 4319.8 4498.7 4508.5 4508.6 4610.8 4328.4 4356.2 4412.9 4420.0 4497.8 4392.7 4426.0 4445.0 4446.6 4467.4 4397.8 4428.2 4433.7 4475.6 4501.8 4502.3 4568.0 4634.1 4774.7 4816.6 5147.8 5167.9 5168.2 5181.8 5238.4 5003.0 5211.8 5222.7 5223.0 5224.2 4689.1 4700.5 4708.0 4731.4 4738.8 5351.5 5352.9 5363.6 5372.7 5450.9 5102.6 5121.0 5123.5 5129.3 5170.8 4845.8 4960.9 4966.5 4970.5 5253.4 4939.4 5008.2 5032.1 5127.2 5149.9 5094.8 5101.9 5102.7 5110.4 5148.7 4285.1 4297.4 4310.2 4311.0 4346.8 3646.4 3674.7 3759.0 3762.1 3780.4 4510.8 4535.3 4549.5 4552.3 4608.7 4958.8 5247.9 5248.8 5257.2 5264.5 4437.8 4474.4 4494.1 4498.5 4548.1 4592.9 4603.0 4626.6 4628.3 4635.0 4709.8 4892.9 4964.5 4967.3 4978.6 5067.3 5107.9 5141.2 5142.5 5153.4 4920.0 4963.7 4964.6 4966.8 4989.7 4875.8 4991.7 4995.1 5146.9 5183.8 5114.9 5190.9 5216.2 5225.9 5260.9 5044.3 5054.0 5061.5 5062.4 5117.2 4797.1 4862.1 4937.9 5055.6 5056.1 4927.2 4934.1 4983.7 5012.8 5016.0 5121.3 5124.0 5139.6 5141.6 5167.1 4833.6 4840.3 4948.8 4950.6 5008.9 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,597 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:38370.77 Median:19101.90 stddev:58003.18 (303.7%) stddev-sans-first:58096.40 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: Values: 557.9 565.0 566.3 575.0 585.8 34741.3 35425.3 35515.0 35564.6 35640.0 38925.0 39382.4 39429.4 39532.4 39699.6 7885.6 8065.8 8078.9 8085.4 8088.6 7882.6 7994.6 8006.3 8007.9 8016.6 7761.3 7763.5 7851.0 7865.8 7876.2 7886.1 7890.8 7945.6 8001.9 8025.4 7816.1 7900.8 7940.3 7945.1 7963.0 36540.3 37145.4 37158.4 37185.1 37194.6 3069.8 3084.5 3109.0 3332.2 3462.5 28009.7 28399.7 28518.7 28739.4 28908.3 3433.7 3439.2 3491.4 3516.4 3539.3 206542.0 209462.0 209897.0 210000.0 210273.0 50326.5 51595.2 53809.2 54160.7 54442.7 48353.0 48444.5 48525.8 48591.0 48687.8 12715.0 12930.9 12970.7 12975.3 12978.9 9720.9 9739.1 9749.1 9793.2 9827.6 18552.2 18655.8 18711.0 18753.6 18779.0 7604.5 7718.4 7785.2 7856.8 7892.0 880.7 906.1 911.6 949.0 962.0 26006.6 26383.6 26710.5 26755.6 26809.9 992.8 1033.6 1071.2 1075.4 1124.3 13615.9 13723.7 14087.4 14578.5 14799.3 255391.0 258345.0 259786.0 260134.0 260353.0 46790.5 46988.8 47023.8 47239.0 47261.8 1201.8 1226.3 1232.9 1241.2 1246.2 26001.9 26373.7 26418.6 26515.5 26578.9 42740.8 44070.0 44189.8 44322.6 44390.8 195249.0 196995.0 197832.0 197964.0 198830.0 29056.3 29203.9 29463.9 29677.3 29842.9 34757.8 35470.3 37527.8 37668.4 38375.4 32023.5 32311.4 32318.2 33073.2 33276.8 32672.3 32783.7 32797.8 33110.7 33111.2 32137.2 32539.0 32619.0 32817.7 32881.0 28728.1 28860.7 29488.7 29612.0 29680.4 22996.2 23104.8 23349.2 23350.0 23394.0 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27482.42 Median:458.42 stddev:87065.56 (18992.7%) stddev-sans-first:87285.94 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: Values: 337.8 338.1 340.7 342.3 343.0 14959.4 15101.9 16161.8 16283.0 16789.4 14832.2 14870.5 14874.7 14956.9 15251.2 4418.1 4642.4 4695.5 4698.3 4702.6 4654.8 4689.5 4693.7 4694.7 4696.2 3403.2 3516.6 4690.7 4691.8 5146.2 5245.4 5288.4 5300.8 5302.3 5320.8 4960.1 4978.4 4979.1 4999.5 5096.4 11506.4 11789.3 11792.0 14859.7 19110.7 1822.8 1888.9 1913.6 1928.3 1972.6 9074.4 9276.5 9344.4 11768.8 12374.2 1745.8 1756.7 1762.1 1777.7 1797.1 54060.5 55165.2 57187.4 57515.1 58309.7 9591.6 9678.4 9682.6 9725.6 9762.6 12051.3 12130.8 12163.0 12185.3 12247.4 6838.2 7011.3 7087.3 7096.3 7143.7 6665.4 6698.3 6718.4 6777.8 6777.8 4231.8 4256.8 4262.4 4289.5 4294.4 1786.2 1796.7 1801.5 1817.3 1837.4 823.3 836.5 837.4 874.9 904.4 15079.9 15414.3 15851.2 15895.8 15895.9 837.4 842.1 848.1 871.4 878.8 5590.9 8949.9 9047.4 9143.5 9276.8 526459.0 530190.0 531790.0 532124.0 545748.0 37701.2 37706.0 77374.2 78724.0 82851.3 444.4 456.4 457.9 458.9 459.3 9714.2 9747.6 9863.6 9876.6 10078.3 34793.7 36357.1 36568.3 36718.3 37418.4 47289.8 47673.0 48063.8 49345.3 49501.3 8772.9 8851.5 8871.4 8892.9 8969.1 15865.9 16071.7 16474.3 16606.7 16902.7 14019.2 14060.1 14469.0 14657.4 14658.3 13591.6 13726.5 13756.4 13757.2 13951.6 14935.9 14964.5 15169.5 15217.6 15431.8 9148.7 9203.3 9343.0 9349.8 9352.2 10809.9 13907.2 14262.5 14455.2 14510.6 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5034.22 Median:6321.70 stddev:2670.46 (42.2%) stddev-sans-first:2664.39 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: Values: 1453.7 1687.2 1741.8 1756.6 1767.6 6135.8 6378.9 7065.4 7200.0 7307.0 6904.1 7234.6 7256.0 7290.1 7317.7 2069.1 2085.9 2089.3 2094.4 2108.9 2105.1 2114.1 2121.8 2159.4 2164.7 2064.1 2099.8 2111.4 2121.1 2133.8 2048.9 2072.9 2074.0 2076.4 2079.4 2057.6 2090.7 2128.6 2140.2 2149.5 7234.6 7275.0 7290.7 7301.8 7319.6 4153.8 4234.1 4240.0 4247.0 4249.6 2740.0 2743.5 2763.0 2765.3 2766.2 875.3 881.5 889.6 909.8 926.2 9775.3 9823.8 9843.1 9854.7 9864.2 3934.5 3950.8 4009.8 4010.0 4016.2 8447.2 8487.4 8489.9 8490.6 8513.5 4244.2 4261.1 4262.5 4280.1 4288.1 3310.7 3408.7 3855.7 3870.3 3874.9 3375.4 3430.7 3440.7 3460.7 3467.3 1084.4 1094.7 1097.3 1103.6 1103.8 758.3 770.2 770.7 771.1 837.3 6697.1 6716.4 6731.3 6734.5 6736.1 410.8 418.8 419.5 422.1 431.5 5151.8 5174.9 5227.1 5243.7 5245.3 8712.2 8728.7 8881.6 8897.5 9014.0 8783.3 8806.0 8806.4 8814.4 8872.5 6682.1 6852.6 6852.8 6865.8 6901.7 6975.8 7027.5 7027.7 7032.5 7042.8 7838.6 7856.7 7946.7 8037.8 8061.0 7698.3 7843.1 7897.0 7934.6 8086.9 5251.4 5518.9 6320.6 6409.5 6498.8 6781.7 6811.8 6861.8 6865.7 6876.6 6364.7 6574.8 6620.5 6694.3 6705.5 6134.9 6263.8 6322.8 6541.9 6565.0 6359.3 6413.5 6414.9 6419.4 6425.5 6095.9 6100.7 6144.5 6232.1 6299.1 6182.5 6215.4 6236.9 6239.8 6257.2 04:27:59 INFO - 2015-12-14 04:27:59,599 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,600 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4918.73 Median:5499.74 stddev:1471.76 (26.8%) stddev-sans-first:1434.56 04:27:59 INFO - 2015-12-14 04:27:59,600 DEBUG : BROWSER_OUTPUT: Values: 304.4 312.9 314.7 315.1 318.2 5431.6 5609.3 5625.5 5639.9 5656.8 5534.7 5549.5 5549.9 5550.8 5556.3 4182.4 4290.4 4310.8 4312.1 4330.2 4295.1 4303.9 4329.2 4350.2 4355.2 4283.5 4291.1 4291.2 4293.2 4301.4 4255.8 4289.4 4290.7 4298.1 4315.7 3656.5 3730.6 3733.8 3742.8 3757.8 5316.4 5445.2 5448.9 5453.4 5464.8 4921.0 4951.5 4953.2 4960.5 4962.7 5925.4 5978.1 5982.3 5995.6 6002.2 3977.9 4044.2 4064.8 4065.6 4078.9 6167.4 6261.2 6264.2 6270.6 6282.8 4176.2 4186.9 4187.5 4192.1 4193.4 4234.3 4251.1 4256.7 4259.8 4269.1 4726.2 4862.4 4883.9 4899.7 4908.1 5933.3 5942.3 5956.3 5959.1 5966.7 1471.0 1494.9 1503.1 1508.1 1508.7 1004.0 1013.9 1016.7 1029.5 1030.6 5198.4 5220.7 5220.9 5231.8 5249.3 6127.1 6139.6 6148.2 6191.0 6192.9 5203.9 5229.6 5234.7 5239.3 5248.7 3263.9 3322.8 3349.8 3374.1 3381.8 5753.6 5894.3 5896.1 5900.4 5903.4 6420.5 6454.1 6454.4 6455.9 6463.9 5772.9 5845.4 5853.2 5884.8 5897.9 6033.0 6193.3 6225.3 6241.0 6244.3 6071.3 6074.8 6079.3 6079.5 6082.9 5840.4 5867.0 5884.9 5895.4 5896.2 4133.2 4151.1 4156.6 4166.1 4177.8 6060.3 6191.0 6192.5 6197.2 6213.0 5912.2 5975.7 5976.3 5983.8 5985.6 5910.7 5960.0 5961.3 5964.7 5976.5 5691.4 5830.1 5840.2 5874.1 5874.7 6032.2 6038.9 6044.8 6047.4 6052.3 5880.7 5909.4 5916.1 5917.7 5922.2 04:27:59 INFO - 2015-12-14 04:27:59,600 DEBUG : BROWSER_OUTPUT: 04:27:59 INFO - 2015-12-14 04:27:59,600 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12110.43 Median:44108.45 stddev:13541.45 (30.7%) stddev-sans-first:13552.17 04:27:59 INFO - 2015-12-14 04:27:59,602 DEBUG : BROWSER_OUTPUT: Values: 668.9 772.9 781.5 785.0 791.5 11689.0 11829.2 11866.8 11891.1 11895.3 10511.4 11011.8 11066.6 11429.7 11454.4 775.4 798.2 799.3 800.2 800.5 778.5 782.3 782.8 784.8 787.0 811.7 819.0 825.6 826.7 828.8 817.0 818.9 820.4 821.6 824.4 610.6 613.9 614.3 614.4 614.4 10587.7 10614.8 10625.8 10649.9 10669.0 9248.9 9476.6 11060.6 11129.8 11266.5 9286.4 9293.6 9515.8 9673.3 9818.0 3240.0 3282.5 3289.3 3289.6 3302.2 37248.0 38137.2 38169.0 38241.2 39688.2 8920.6 9066.4 9178.2 9199.5 9271.5 15996.4 16073.0 16103.6 16148.6 16159.8 6164.1 6238.8 6244.5 6274.9 6471.2 3452.9 3489.4 3551.4 3559.2 3604.4 2690.3 2708.4 2709.5 2726.4 2753.0 498.6 499.8 503.8 506.1 510.5 458.7 472.2 586.8 620.1 633.0 7478.2 8040.4 8369.1 8454.6 8758.3 491.5 492.8 560.9 585.4 628.4 3788.9 3985.3 4125.6 4128.2 4255.8 49294.8 49729.3 49731.0 49786.0 49944.0 43600.0 43792.6 44095.3 44121.6 44251.3 37519.4 39099.6 39151.6 39326.8 39974.2 8256.6 8262.7 8345.5 8925.9 8984.7 30267.8 30503.4 31244.4 31293.5 32557.0 39081.8 39358.0 39672.0 39878.6 40103.8 10289.4 10382.6 10531.5 10629.9 10671.0 10763.3 10976.9 11067.4 11175.4 11640.7 10037.8 10601.2 10827.6 11069.9 11078.3 10521.4 10640.8 10668.3 10715.7 10729.2 11031.2 11191.2 11198.2 11213.4 11251.9 9098.9 9151.8 9230.2 9471.4 9494.4 9030.4 9058.8 9117.8 9270.8 9287.3 04:27:59 INFO - 2015-12-14 04:27:59,602 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:27:59 INFO - 2015-12-14 04:27:59,602 DEBUG : BROWSER_OUTPUT: 04:28:00 INFO - 2015-12-14 04:28:00,310 INFO : Browser exited with error code: 0 04:28:00 INFO - 2015-12-14 04:28:00,331 INFO : Completed test dromaeo_css (00:18:34) 04:28:00 INFO - 2015-12-14 04:28:00,331 INFO : Starting test kraken 04:28:00 INFO - 2015-12-14 04:28:00,331 DEBUG : operating with platform_type : linux_ 04:28:00 INFO - 2015-12-14 04:28:00,331 INFO : Initialising browser for kraken test... 04:28:00 INFO - 2015-12-14 04:28:00,337 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXRbycK/profile http://localhost:48964/getInfo.html 04:28:04 INFO - 2015-12-14 04:28:04,575 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:28:04 INFO - 2015-12-14 04:28:04,575 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:28:04 INFO - 2015-12-14 04:28:04,576 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:28:04 INFO - 2015-12-14 04:28:04,576 DEBUG : BROWSER_OUTPUT: __metrics 04:28:04 INFO - 2015-12-14 04:28:04,704 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] 04:28:05 INFO - 2015-12-14 04:28:05,597 INFO : Browser initialized. 04:28:05 INFO - 2015-12-14 04:28:05,598 INFO : Running cycle 1/1 for kraken test... 04:28:05 INFO - 2015-12-14 04:28:05,598 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXRbycK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:28:17 INFO - 2015-12-14 04:28:17,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 142790656 04:28:17 INFO - 2015-12-14 04:28:17,642 DEBUG : BROWSER_OUTPUT: 04:28:20 INFO - 2015-12-14 04:28:20,188 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48964/tests/kraken/driver.html?testName=audio-beat-detection) 04:28:20 INFO - 2015-12-14 04:28:20,809 DEBUG : BROWSER_OUTPUT: RSS: Main: 141975552 04:28:20 INFO - 2015-12-14 04:28:20,809 DEBUG : BROWSER_OUTPUT: 04:28:23 INFO - 2015-12-14 04:28:23,559 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48964/tests/kraken/driver.html?testName=audio-dft) 04:28:24 INFO - 2015-12-14 04:28:24,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 142848000 04:28:24 INFO - 2015-12-14 04:28:24,153 DEBUG : BROWSER_OUTPUT: 04:28:27 INFO - 2015-12-14 04:28:27,017 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48964/tests/kraken/driver.html?testName=audio-fft) 04:28:27 INFO - 2015-12-14 04:28:27,610 DEBUG : BROWSER_OUTPUT: RSS: Main: 138063872 04:28:27 INFO - 2015-12-14 04:28:27,610 DEBUG : BROWSER_OUTPUT: 04:28:29 INFO - 2015-12-14 04:28:29,723 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48964/tests/kraken/driver.html?testName=audio-oscillator) 04:28:30 INFO - 2015-12-14 04:28:30,344 DEBUG : BROWSER_OUTPUT: RSS: Main: 137895936 04:28:30 INFO - 2015-12-14 04:28:30,345 DEBUG : BROWSER_OUTPUT: 04:28:32 INFO - 2015-12-14 04:28:32,603 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48964/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:33 INFO - 2015-12-14 04:28:33,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 139583488 04:28:33 INFO - 2015-12-14 04:28:33,190 DEBUG : BROWSER_OUTPUT: 04:28:36 INFO - 2015-12-14 04:28:36,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48964/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:36 INFO - 2015-12-14 04:28:36,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 140890112 04:28:36 INFO - 2015-12-14 04:28:36,836 DEBUG : BROWSER_OUTPUT: 04:28:40 INFO - 2015-12-14 04:28:40,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48964/tests/kraken/driver.html?testName=imaging-desaturate) 04:28:40 INFO - 2015-12-14 04:28:40,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 138551296 04:28:40 INFO - 2015-12-14 04:28:40,908 DEBUG : BROWSER_OUTPUT: 04:28:43 INFO - 2015-12-14 04:28:43,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48964/tests/kraken/driver.html?testName=json-parse-financial) 04:28:44 INFO - 2015-12-14 04:28:44,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 139558912 04:28:44 INFO - 2015-12-14 04:28:44,414 DEBUG : BROWSER_OUTPUT: 04:28:46 INFO - 2015-12-14 04:28:46,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48964/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:28:46 INFO - 2015-12-14 04:28:46,790 DEBUG : BROWSER_OUTPUT: RSS: Main: 139816960 04:28:46 INFO - 2015-12-14 04:28:46,790 DEBUG : BROWSER_OUTPUT: 04:28:48 INFO - 2015-12-14 04:28:48,685 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:28:49 INFO - 2015-12-14 04:28:49,307 DEBUG : BROWSER_OUTPUT: RSS: Main: 140308480 04:28:49 INFO - 2015-12-14 04:28:49,307 DEBUG : BROWSER_OUTPUT: 04:28:51 INFO - 2015-12-14 04:28:51,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:28:52 INFO - 2015-12-14 04:28:52,015 DEBUG : BROWSER_OUTPUT: RSS: Main: 140091392 04:28:52 INFO - 2015-12-14 04:28:52,015 DEBUG : BROWSER_OUTPUT: 04:28:54 INFO - 2015-12-14 04:28:54,599 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:28:55 INFO - 2015-12-14 04:28:55,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 140537856 04:28:55 INFO - 2015-12-14 04:28:55,201 DEBUG : BROWSER_OUTPUT: 04:28:57 INFO - 2015-12-14 04:28:57,817 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:28:58 INFO - 2015-12-14 04:28:58,419 DEBUG : BROWSER_OUTPUT: RSS: Main: 141627392 04:28:58 INFO - 2015-12-14 04:28:58,419 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,193 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48964/tests/kraken/driver.html?testName=ai-astar) 04:29:00 INFO - 2015-12-14 04:29:00,443 DEBUG : BROWSER_OUTPUT: RSS: Main: 140574720 04:29:00 INFO - 2015-12-14 04:29:00,443 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;106;114;113;113;125;111;112;111;111 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;137;154;169;130;155;157;138;171;138 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;164;162;164;162;165;170;161;163;164 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;74;72;83;75;138;72;72;72 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;128;94;97;95;143;95;95;131 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;110;111;110;110;110;110 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;148;147;149;148;149;147;149;148 04:29:00 INFO - 2015-12-14 04:29:00,445 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;97;96;95;96;96;96;96;96 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;75;73;72;75;73;77;72;73;81 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;67;68;68;71;68;68;68;67;69 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;93;84;86;85;84;84;87;86;84;84 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;136;122;123;125;135;129;128;122;132;130 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;154;155;146;149;139;146;147;151;138 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;73;69;69;69;70;73;69;69;70 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,786 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: __startTimestamp1450096140443__endTimestamp 04:29:00 INFO - 2015-12-14 04:29:00,446 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.80 Median:112.50 stddev:4.80 (4.3%) stddev-sans-first:5.09 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: Values: 112.0 106.0 114.0 113.0 113.0 125.0 111.0 112.0 111.0 111.0 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.60 Median:156.00 stddev:14.12 (9.1%) stddev-sans-first:14.79 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: Values: 157.0 137.0 154.0 169.0 130.0 155.0 157.0 138.0 171.0 138.0 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:2.54 (1.5%) stddev-sans-first:2.62 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: Values: 162.0 164.0 162.0 164.0 162.0 165.0 170.0 161.0 163.0 164.0 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.30 Median:72.50 stddev:20.55 (28.3%) stddev-sans-first:21.58 04:29:00 INFO - 2015-12-14 04:29:00,450 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 74.0 72.0 83.0 75.0 138.0 72.0 72.0 72.0 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.70 Median:95.00 stddev:19.22 (20.2%) stddev-sans-first:19.92 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 128.0 94.0 97.0 95.0 143.0 95.0 95.0 131.0 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 110.0 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 148.0 147.0 149.0 148.0 149.0 147.0 149.0 148.0 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 97.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 04:29:00 INFO - 2015-12-14 04:29:00,451 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.50 Median:74.50 stddev:2.76 (3.7%) stddev-sans-first:2.92 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: Values: 74.0 75.0 73.0 72.0 75.0 73.0 77.0 72.0 73.0 81.0 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.20 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 68.0 68.0 71.0 68.0 68.0 68.0 67.0 69.0 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.50 stddev:2.79 (3.3%) stddev-sans-first:1.17 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: Values: 93.0 84.0 86.0 85.0 84.0 84.0 87.0 86.0 84.0 84.0 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,452 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:129.50 stddev:5.16 (4.0%) stddev-sans-first:4.64 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: Values: 136.0 122.0 123.0 125.0 135.0 129.0 128.0 122.0 132.0 130.0 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.20 Median:148.00 stddev:5.57 (3.8%) stddev-sans-first:5.91 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: Values: 147.0 154.0 155.0 146.0 149.0 139.0 146.0 147.0 151.0 138.0 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.00 stddev:1.60 (2.3%) stddev-sans-first:1.69 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: Values: 70.0 73.0 69.0 69.0 69.0 70.0 73.0 69.0 69.0 70.0 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:29:00 INFO - 2015-12-14 04:29:00,453 DEBUG : BROWSER_OUTPUT: 04:29:01 INFO - 2015-12-14 04:29:01,365 INFO : Browser exited with error code: 0 04:29:01 INFO - 2015-12-14 04:29:01,419 INFO : Completed test kraken (00:01:01) 04:29:01 INFO - 2015-12-14 04:29:01,419 INFO : Starting test v8_7 04:29:01 INFO - 2015-12-14 04:29:01,419 DEBUG : operating with platform_type : linux_ 04:29:01 INFO - 2015-12-14 04:29:01,419 INFO : Initialising browser for v8_7 test... 04:29:01 INFO - 2015-12-14 04:29:01,425 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAiscvC/profile http://localhost:48964/getInfo.html 04:29:05 INFO - 2015-12-14 04:29:05,664 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:29:05 INFO - 2015-12-14 04:29:05,664 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:29:05 INFO - 2015-12-14 04:29:05,664 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:29:05 INFO - 2015-12-14 04:29:05,665 DEBUG : BROWSER_OUTPUT: __metrics 04:29:05 INFO - 2015-12-14 04:29:05,766 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] 04:29:06 INFO - 2015-12-14 04:29:06,629 INFO : Browser initialized. 04:29:06 INFO - 2015-12-14 04:29:06,630 INFO : Running cycle 1/1 for v8_7 test... 04:29:06 INFO - 2015-12-14 04:29:06,630 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAiscvC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:29:18 INFO - 2015-12-14 04:29:18,651 DEBUG : BROWSER_OUTPUT: RSS: Main: 142942208 04:29:18 INFO - 2015-12-14 04:29:18,651 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,034 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48964/tests/v8_7/run.html (next: http://localhost:48964/tests/v8_7/run.html) 04:29:40 INFO - 2015-12-14 04:29:40,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 137879552 04:29:40 INFO - 2015-12-14 04:29:40,284 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,284 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |0;Richards;165.89250165892503 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.9508319312021 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |2;Encrypt;276.8549280177187 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1071.8113612004288 04:29:40 INFO - 2015-12-14 04:29:40,285 DEBUG : BROWSER_OUTPUT: |5;Earley;750.1875468867216 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: |6;Boyer;12243.90243902439 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: |7;RegExp;26947.36842105263 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: |8;Splay;593.8242280285035 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: __startTimestamp1450096180282__endTimestamp 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.89 Median:165.89 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: Values: 165.9 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,286 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.95 Median:186.95 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: Values: 187.0 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:276.85 Median:276.85 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: Values: 276.9 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: Values: 5133.3 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1071.81 Median:1071.81 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: Values: 1071.8 04:29:40 INFO - 2015-12-14 04:29:40,287 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:750.19 Median:750.19 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: Values: 750.2 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12243.90 Median:12243.90 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: Values: 12243.9 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26947.37 Median:26947.37 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: Values: 26947.4 04:29:40 INFO - 2015-12-14 04:29:40,288 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.82 Median:593.82 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: Values: 593.8 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: Values: 4845.4 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:29:40 INFO - 2015-12-14 04:29:40,290 DEBUG : BROWSER_OUTPUT: 04:29:41 INFO - 2015-12-14 04:29:41,209 INFO : Browser exited with error code: 0 04:29:41 INFO - 2015-12-14 04:29:41,224 INFO : Completed test v8_7 (00:00:39) 04:29:41 INFO - 2015-12-14 04:29:41,320 INFO : Completed test suite (00:20:15) 04:29:41 INFO - 2015-12-14 04:29:41,320 DEBUG : Working with test: dromaeo_css 04:29:41 INFO - 2015-12-14 04:29:41,320 DEBUG : Generating results file: dromaeo_css 04:29:41 INFO - 2015-12-14 04:29:41,321 DEBUG : Working with test: kraken 04:29:41 INFO - 2015-12-14 04:29:41,321 DEBUG : Generating results file: kraken 04:29:41 INFO - 2015-12-14 04:29:41,322 INFO : javascript benchmark 04:29:41 INFO - 2015-12-14 04:29:41,322 DEBUG : Working with test: v8_7 04:29:41 INFO - 2015-12-14 04:29:41,322 DEBUG : Generating results file: v8_7 04:29:41 INFO - 2015-12-14 04:29:41,322 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:29:41 INFO - 2015-12-14 04:29:41,375 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 04:29:41 INFO - 2015-12-14 04:29:41,375 DEBUG : process_Request line: dromaeo_css 6331.14 graph.html#tests=[[72,94,43]] 04:29:41 INFO - 2015-12-14 04:29:41,375 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:29:41 INFO - 2015-12-14 04:29:41,405 DEBUG : process_Request line: kraken 1541.60 graph.html#tests=[[232,94,43]] 04:29:41 INFO - 2015-12-14 04:29:41,405 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:29:41 INFO - 2015-12-14 04:29:41,440 DEBUG : process_Request line: v8_7 21223.81 graph.html#tests=[[230,94,43]] 04:29:41 INFO - 2015-12-14 04:29:41,441 INFO : javascript benchmark 04:29:41 INFO - 2015-12-14 04:29:41,445 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2676.2640273924676, 2707.5532537131376, 2770.168949315206, 2784.4152727927135, 2785.234060633324, 4663.463701674308, 4820.710757354561, 4821.783019764106, 4846.951530484694, 4863.0760155570415, 4687.086018226447, 4703.435587729477, 4761.047790441909, 4816.060868130708, 4831.613470922327, 4387.210302145612, 4469.798859051342, 4518.367428559988, 4540.250858608342, 4574.885627859314, 4319.82720691172, 4498.707584007046, 4508.458984921814, 4508.571685689871, 4610.815567377301, 4328.350747387917, 4356.215881141393, 4412.933805992929, 4419.977900110479, 4497.820087196508, 4392.714473559208, 4426.0, 4444.955550444486, 4446.555344465528, 4467.441569803775, 4397.780110994438, 4428.247197976351, 4433.689641725081, 4475.59719625232, 4501.842435514774, 4502.324651302298, 4568.0, 4634.073185362941, 4774.689645173053, 4816.614670826326, 5147.7683504242395, 5167.896642067175, 5168.198929165986, 5181.766820493049, 5238.371395432572, 5003.0, 5211.765470553834, 5222.738863056832, 5222.97388513055, 5224.190250511177, 4689.132510485571, 4700.4594471635855, 4708.0, 4731.361266229015, 4738.810447582127, 5351.464853514617, 5352.91970620441, 5363.570914326844, 5372.7313634318125, 5450.945490545044, 5102.642815002914, 5120.974395128075, 5123.462036486116, 5129.281900533928, 5170.793168273302, 4845.75771211438, 4960.851174464772, 4966.453690094087, 4970.527799859007, 5253.36959564847, 4939.382577177853, 5008.223725322508, 5032.144535428986, 5127.179651255781, 5149.871253218699, 5094.77073531692, 5101.948980510221, 5102.744862756922, 5110.361204849394, 5148.665336753099, 4285.078708077778, 4297.441332626801, 4310.181065597527, 4311.0300182458695, 4346.7826608670075, 3646.361886669822, 3674.7427680062674, 3759.0414444316525, 3762.1159027628487, 3780.3951367781024, 4510.774461276923, 4535.319702044654, 4549.476810166807, 4552.271636538138, 4608.654350923627, 4958.826441074617, 5247.921281180786, 5248.816291429858, 5257.237700533401, 5264.499872512105, 4437.755923424178, 4474.418325617649, 4494.123645889085, 4498.527654596286, 4548.090381923628, 4592.862214133581, 4602.953970460252, 4626.560476754703, 4628.305754136906, 4634.97682511592, 4709.764511774466, 4892.926606100982, 4964.528369804862, 4967.304577359173, 4978.626603004716, 5067.290579318972, 5107.923381149211, 5141.177411614124, 5142.460041695568, 5153.433122356612, 4920.0, 4963.677360971526, 4964.578010868972, 4966.801327946841, 4989.675671081368, 4875.780589873535, 4991.700497970133, 4995.100881841304, 5146.871328216675, 5183.766730497061, 5114.874727559931, 5190.922136168036, 5216.191490319006, 5225.869353266199, 5260.894782104413, 5044.344235249394, 5053.974730126326, 5061.519155680205, 5062.367204099488, 5117.232415137759, 4797.136515427256, 4862.05189987949, 4937.9012419752125, 5055.620828437955, 5056.115179843586, 4927.162382394929, 4934.111859865258, 4983.700977941335, 5012.8496145115705, 5016.0, 5121.308623335951, 5124.0, 5139.640225184201, 5141.5629671476845, 5167.069927413102, 4833.637477189154, 4840.34655321527, 4948.802047918043, 4950.579200767975, 5008.949910500848], "unit": "ms", "name": "dojo.html", "value": 4730.295223130735}, {"lowerIsBetter": false, "replicates": [557.8995780759462, 564.9717514124293, 566.3091028944687, 574.9885002299955, 585.833037584288, 34741.30517389654, 35425.29149417013, 35515.0, 35564.644353556454, 35640.0, 38925.0, 39382.40926386107, 39429.40855887164, 39532.40701389474, 39699.603003969954, 7885.605719714005, 8065.758027259191, 8078.919210807875, 8085.3531717462465, 8088.636011379502, 7882.645280962371, 7994.64024118916, 8006.319462845665, 8007.919920800776, 8016.63925123368, 7761.3402860756905, 7763.495372800779, 7851.0, 7865.842683146334, 7876.173001834811, 7886.053673559181, 7890.763277101696, 7945.6424460898825, 8001.8799718004275, 8025.357971362306, 7816.140224575292, 7900.762977110696, 7940.325072368855, 7945.086315073782, 7962.960185199095, 36540.26919461596, 37145.44281835775, 37158.44262336067, 37185.07037324062, 37194.62805371939, 3069.785115041948, 3084.4602194615854, 3108.9689103108904, 3332.166958260435, 3462.4979377990303, 28009.719902800916, 28399.716002839912, 28518.714812851813, 28739.42521149586, 28908.277293067633, 3433.7424693147864, 3439.1917899293644, 3491.398653702319, 3516.4197907345256, 3539.256756081226, 206542.0, 209461.95269023709, 209897.0, 210000.0, 210273.0, 50326.49673503218, 51595.22607160896, 53809.1928621071, 54160.72919635456, 54442.727786361604, 48353.0, 48444.515554844, 48525.75737121362, 48591.0, 48687.75656121768, 12715.0, 12930.870691292967, 12970.740585188243, 12975.286359250333, 12978.935105324601, 9720.854187187198, 9739.123478886793, 9749.122578967927, 9793.167580755573, 9827.606895724233, 18552.165152568166, 18655.813441865677, 18711.0, 18753.624927501645, 18779.028202038862, 7604.467687261949, 7718.421118416141, 7785.221477852169, 7856.803579910548, 7891.960540197377, 880.6521424037469, 906.1255888068046, 911.6034524981521, 948.9952550237205, 961.9519024048909, 26006.6099008515, 26383.590644910284, 26710.465790684077, 26755.598666020025, 26809.86595067051, 992.7915137821138, 1033.560736686905, 1071.2180108520824, 1075.44077079918, 1124.2973141786383, 13615.863841361459, 13723.656908577166, 14087.36606852694, 14578.489752858599, 14799.260036998106, 255391.0, 258345.0, 259786.0, 260134.0, 260353.0, 46790.5320946793, 46988.76505617519, 47023.764881175375, 47239.0, 47261.763691181324, 1201.8377519034993, 1226.2519862883635, 1232.9260244385366, 1241.1560139105495, 1246.214884622682, 26001.869990649924, 26373.73626263713, 26418.60372094382, 26515.469690605692, 26578.867105664347, 42740.786296069564, 44070.0, 44189.77905110454, 44322.556774433135, 44390.778046110856, 195249.0, 196995.0, 197832.0, 197964.0, 198830.0, 29056.27359316034, 29203.85398072996, 29463.852680736458, 29677.258068548457, 29842.850785745934, 34757.826210868785, 35470.290594187456, 37527.81236093802, 37668.43497347487, 38375.42436863505, 32023.519647204845, 32311.35377292394, 32318.19204519906, 33073.173170670925, 33276.83361583177, 32672.343635244317, 32783.67216327806, 32797.836010819796, 33110.66889331076, 33111.17222069467, 32137.196570085936, 32539.0, 32619.0, 32817.67182328146, 32881.0, 28728.13815585536, 28860.7113928858, 29488.705112949454, 29612.0, 29680.406391872475, 22996.19513317026, 23104.768952310933, 23349.182778602666, 23350.0, 23394.0], "unit": "ms", "name": "ext.html", "value": 16656.41894328918}, {"lowerIsBetter": false, "replicates": [337.82095489390633, 338.08210707927987, 340.7103961632611, 342.28633299570396, 342.98799542016036, 14959.40162393503, 15101.924490377547, 16161.757573636405, 16283.0, 16789.41237056703, 14832.184229867353, 14870.47953321634, 14874.702505949901, 14956.850431495683, 15251.237438128104, 4418.116376724652, 4642.3500709900645, 4695.483496815347, 4698.271767875967, 4702.553257440538, 4654.767261636922, 4689.460712018127, 4693.671442998992, 4694.694844835092, 4696.154692155411, 3403.1662242750563, 3516.6483351664756, 4690.742009189493, 4691.812327506913, 5146.150885103953, 5245.370555533323, 5288.444713305105, 5300.761465734051, 5302.3106996090455, 5320.76056577453, 4960.057589058103, 4978.427480839678, 4979.10376132296, 4999.450060493343, 5096.362954630671, 11506.424678766112, 11789.292642441394, 11792.0, 14859.702805943929, 19110.71333929992, 1822.8359447649775, 1888.9149988250563, 1913.5885784556388, 1928.3347245200384, 1972.5561748606592, 9074.36479446439, 9276.489793061379, 9344.392614480104, 11768.764624707543, 12374.195677281037, 1745.7643218165513, 1756.7277072053723, 1762.1101343821417, 1777.7022773375509, 1797.128392729525, 54060.45939540633, 55165.1725224122, 57187.42812571821, 57515.13727294094, 58309.708451457474, 9591.568379422803, 9678.419294842332, 9682.612695492242, 9725.61097556104, 9762.609495620096, 12051.337176455203, 12130.75738485243, 12163.0, 12185.329806860707, 12247.448864801105, 6838.213605435439, 7011.3339232772805, 7087.291270872975, 7096.254893236239, 7143.678534465961, 6665.366790154928, 6698.296678848748, 6718.428933540704, 6777.762778302835, 6777.830554236085, 4231.830726770895, 4256.808443620044, 4262.360645903077, 4289.506706728704, 4294.377315289265, 1786.2319202742685, 1796.694561924476, 1801.4505575799244, 1817.318505560415, 1837.366108692499, 823.2878560045517, 836.5482639374669, 837.4347315561971, 874.8775171475999, 904.3533873280636, 15079.924600376928, 15414.306356213998, 15851.207439627973, 15895.761563576556, 15895.920520397323, 837.3719710217337, 842.0568962761623, 848.0713618587637, 871.3987348729356, 878.7715194049593, 5590.91613625796, 8949.865752013726, 9047.411918225294, 9143.49710765901, 9276.814463710823, 526459.0, 530190.0, 531790.0, 532124.0, 545748.0, 37701.24597508089, 37706.0, 77374.22625773783, 78724.0, 82851.3429731402, 444.37565220864735, 456.38160292802775, 457.93131030345717, 458.8990422107131, 459.2903963376591, 9714.222862170867, 9747.561359738687, 9863.556139973574, 9876.604935802774, 10078.344907580984, 34793.65206347904, 36357.09107272339, 36568.26863462769, 36718.26563468769, 37418.43872341971, 47289.763551182026, 47673.0, 48063.75968120137, 49345.25982110343, 49501.257481138535, 8772.91227087721, 8851.513166775632, 8871.379003469954, 8892.866607000771, 8969.10308969124, 15865.920670396574, 16071.67856642884, 16474.341026358812, 16606.667866642358, 16902.746458803373, 14019.158850469004, 14060.08609440383, 14469.0, 14657.413703451743, 14658.267086645412, 13591.592252232449, 13726.45094196221, 13756.380962856896, 13757.243351615758, 13951.58145255644, 14935.85064149388, 14964.476243331866, 15169.544913652608, 15217.619559510658, 15431.768523471932, 9148.679796207101, 9203.263738900738, 9343.0, 9349.766255843659, 9352.205062569486, 10809.891901081202, 13907.165570065828, 14262.500812471513, 14455.204963727096, 14510.564683059525], "unit": "ms", "name": "jquery.html", "value": 8002.114528127314}, {"lowerIsBetter": false, "replicates": [1453.658390278285, 1687.1901487286102, 1741.8171092035336, 1756.6311074674318, 1767.602289484866, 6135.754569817202, 6378.904316435257, 7065.364117229448, 7200.027996220515, 7307.0, 6904.067950826637, 7234.565926044441, 7256.0, 7290.052293201877, 7317.707291708339, 2069.0896005757454, 2085.9165633374723, 2089.3105275259204, 2094.4240333908147, 2108.947276318089, 2105.0737675422843, 2114.1332053857923, 2121.840861935353, 2159.36298791856, 2164.6969424280614, 2064.143380497092, 2099.8110170084688, 2111.4299139232476, 2121.087932189158, 2133.829293656504, 2048.8565800393976, 2072.8652637578584, 2073.979260207409, 2076.4289820299537, 2079.3761871438583, 2057.6399130152313, 2090.6758254375463, 2128.5742851429623, 2140.2295173737443, 2149.4518897681146, 7234.638268086627, 7275.0, 7290.744823931137, 7301.780946571611, 7319.59742214178, 4153.813078411479, 4234.13200293939, 4240.045989652334, 4246.957530424687, 4249.617534421886, 2740.0409856550245, 2743.5198840203084, 2762.986185069062, 2765.3224959884956, 2766.170148955305, 875.2822685397977, 881.501447539186, 889.5685592487638, 909.8407778638777, 926.1895841139003, 9775.315727898978, 9823.803523929553, 9843.114119729258, 9854.65508707199, 9864.161546268575, 3934.5278566572197, 3950.802459876995, 4009.839606415769, 4009.979950100231, 4016.196760647882, 8447.155284471626, 8487.363447741443, 8489.872651910226, 8490.575471226413, 8513.531755753493, 4244.236037513216, 4261.083866968616, 4262.488501379854, 4280.143971205771, 4288.056627541936, 3310.6854848789326, 3408.727301815849, 3855.7108216883844, 3870.283997460479, 3874.9418758718643, 3375.3586818504896, 3430.708389786846, 3440.690337869604, 3460.688538031539, 3467.3065386922717, 1084.3710647824244, 1094.677070264258, 1097.286763603664, 1103.5751235774285, 1103.8178700514486, 758.2758465665277, 770.1528318849221, 770.6532060572765, 771.1247733822153, 837.3175861672718, 6697.062411262427, 6716.395524402729, 6731.2932142125355, 6734.528582999143, 6736.124303840567, 410.8377191009594, 418.7843260720731, 419.51755481196255, 422.1051371093302, 431.5274774122331, 5151.845444636667, 5174.870628234325, 5227.085260079471, 5243.711595862188, 5245.26566280721, 8712.215900568852, 8728.738137855747, 8881.600327985256, 8897.510636914902, 9013.954930225307, 8783.253423459077, 8805.955970220237, 8806.427582207136, 8814.427062240933, 8872.467651940904, 6682.131322927989, 6852.588844669435, 6852.760153394607, 6865.828354291183, 6901.689423975933, 6975.825604359931, 7027.4729395294535, 7027.7188912442925, 7032.472564557781, 7042.7887163385185, 7838.608069596383, 7856.7250146244605, 7946.682132714858, 8037.8392432152195, 8061.0, 7698.2686644768655, 7843.058832939855, 7897.0, 7934.563599001879, 8086.9191308086165, 5251.36983561975, 5518.944810552004, 6320.62076275425, 6409.519286053472, 6498.805035848932, 6781.660916954232, 6811.761588344384, 6861.759838405632, 6865.725370985105, 6876.621785801829, 6364.681765911779, 6574.8356291093105, 6620.536562440583, 6694.263631000488, 6705.4635629150525, 6134.9079763804475, 6263.843403914939, 6322.778702745566, 6541.901871472027, 6565.015247712881, 6359.300476947439, 6413.4869210463985, 6414.871702565829, 6419.390157934989, 6425.518086143651, 6095.908561371672, 6100.664463454553, 6144.508439324933, 6232.127502149793, 6299.11812346281, 6182.505399567934, 6215.440610345091, 6236.875262494816, 6239.812805615838, 6257.2491301044165], "unit": "ms", "name": "mootools.html", "value": 4022.981095086157}, {"lowerIsBetter": false, "replicates": [304.3532493451416, 312.91551281154074, 314.7324773942148, 315.089391658108, 318.2346456771464, 5431.592630552709, 5609.326880774308, 5625.5218306444, 5639.915401268984, 5656.802011929578, 5534.695591742453, 5549.472800083996, 5549.861253468665, 5550.833474995747, 5556.333240011194, 4182.372644103393, 4290.399344091829, 4310.762908040057, 4312.116016216683, 4330.220560299153, 4295.098029413827, 4303.870883873473, 4329.220740266751, 4350.238708226064, 4355.237833379162, 4283.464566929134, 4291.120320334339, 4291.184674911772, 4293.248681480744, 4301.419308393374, 4255.787210639455, 4289.356596510533, 4290.742555446652, 4298.097399546099, 4315.6763242756915, 3656.4880916671686, 3730.608286129944, 3733.757305775116, 3742.8315725792404, 3757.755745876509, 5316.415194328621, 5445.210444485567, 5448.91826622601, 5453.427390124019, 5464.781408743645, 4921.0, 4951.5048495150195, 4953.23224900141, 4960.454350021495, 4962.6774259673, 5925.377835327315, 5978.133170690226, 5982.312034116088, 5995.610285331483, 6002.249718785152, 3977.9204415911518, 4044.211378781139, 4064.79676016201, 4065.57311482296, 4078.857239996586, 6167.352427995085, 6261.186045814061, 6264.1543391642, 6270.623762574214, 6282.842928926813, 4176.185643799491, 4186.895327616773, 4187.497500299984, 4192.11965487245, 4193.35003074524, 4234.301340278819, 4251.128518653669, 4256.7445953242905, 4259.787010649455, 4269.146170765859, 4726.172919739032, 4862.440819305754, 4883.902321953612, 4899.681520701143, 4908.067467181224, 5933.3473317934995, 5942.346341902387, 5956.285245770535, 5959.135925290895, 5966.701664916737, 1470.9705805883823, 1494.8505149484965, 1503.0981411153439, 1508.057464084947, 1508.7133444645483, 1003.9909890559941, 1013.9239557033252, 1016.7000734783256, 1029.5469993202967, 1030.6341248856697, 5198.376194856642, 5220.686758794484, 5220.92168617471, 5231.790728370898, 5249.291345668286, 6127.142200091991, 6139.600925939889, 6148.231471066117, 6190.969045154746, 6192.938070619326, 5203.9219411708855, 5229.6339256251695, 5234.738263086831, 5239.29269548606, 5248.685078895279, 3263.853126609308, 3322.833858307075, 3349.8325083745717, 3374.1395944034116, 3381.847816848248, 5753.626014309057, 5894.2926848778425, 5896.115582662549, 5900.380460051719, 5903.35063143054, 6420.454261387835, 6454.128692626529, 6454.419102280817, 6455.903161452582, 6463.903041454382, 5772.884542309131, 5845.41545845412, 5853.180554722342, 5884.764609415661, 5897.852553686193, 6033.0, 6193.318734939243, 6225.31521532622, 6241.0, 6244.344343843831, 6071.332153463027, 6074.848128796815, 6079.3312735598165, 6079.510519922647, 6082.905077086167, 5840.386759390372, 5867.031939729897, 5884.882302353843, 5895.380984996514, 5896.233489646319, 4133.152703695795, 4151.066010147692, 4156.605122513356, 4166.0834616384345, 4177.8328866846205, 6060.333363329942, 6190.969045154746, 6192.473639740672, 6197.163382943245, 6213.00591905293, 5912.201852749996, 5975.671338076448, 5976.312724036791, 5983.790567330123, 5985.551083668829, 5910.7340169693325, 5960.003398861257, 5961.2846458425265, 5964.731587078505, 5976.462118409364, 5691.373948865705, 5830.067189249869, 5840.153177789111, 5874.089516125007, 5874.735636896436, 6032.1856549365275, 6038.9396106038375, 6044.788432404845, 6047.365026672136, 6052.303985041776, 5880.705964701833, 5909.379515151019, 5916.05343145112, 5917.733701983335, 5922.200502932222], "unit": "ms", "name": "prototype.html", "value": 4442.22219846515}, {"lowerIsBetter": false, "replicates": [668.9498287628429, 772.9227077292271, 781.4764108047608, 785.0344076785553, 791.5290402210686, 11689.0, 11829.231099978517, 11866.7626647467, 11891.108166887485, 11895.345755983428, 10511.421871797049, 11011.779764404708, 11066.557337706503, 11429.714257143554, 11454.427278636056, 775.4029397364027, 798.1619299735283, 799.2926260259683, 800.2277801921144, 800.4676889868231, 778.4900889917113, 782.3311069035977, 782.8316911863948, 784.7920301120208, 787.016229712859, 811.6509900742678, 818.9549574773386, 825.5707032343178, 826.6982551368767, 828.7762304177868, 816.9714060007872, 818.9303909167699, 820.4216027700487, 821.5686764448683, 824.4352618456377, 610.5512448350458, 613.9478144357735, 614.2996983438883, 614.4193736918613, 614.4408588184775, 10587.682369528926, 10614.787704245871, 10625.787484250272, 10649.893501064967, 10669.0, 9248.861267081, 9476.573554190078, 11060.612878549213, 11129.777404451866, 11266.54933802655, 9286.396384235004, 9293.58178881952, 9515.762105947337, 9673.322867399285, 9817.950910245474, 3239.983800081008, 3282.5404443377947, 3289.3256882339065, 3289.5723555937816, 3302.2239773653173, 37248.0, 38137.23725525474, 38169.0, 38241.23517529634, 39688.20623587541, 8920.598573064228, 9066.410683305565, 9178.21985131258, 9199.4940278284, 9271.490068046322, 15996.360145594279, 16073.0, 16103.597410064842, 16148.596285092732, 16159.757603635955, 6164.106204600308, 6238.844028899314, 6244.531660125418, 6274.937250627527, 6471.158749362553, 3452.8964131075604, 3489.354469423165, 3551.449525323579, 3559.181388280658, 3604.3692353837973, 2690.3274181454635, 2708.404151086758, 2709.4581083783323, 2726.4138210284486, 2753.022676949694, 498.61357447978105, 499.787590274132, 503.84128999365083, 506.12693104394623, 510.5303121128571, 458.69965597526146, 472.15248628711544, 586.7569065146841, 620.0667994667996, 633.0251412824291, 7478.2147874473485, 8040.437169398086, 8369.121242269595, 8454.61954212062, 8758.255548278468, 491.4839418610516, 492.79795283933413, 560.8878224355145, 585.3911931591183, 628.4155735166248, 3788.905277368033, 3985.2627263955683, 4125.628693417607, 4128.195001974646, 4255.76593287366, 49294.75352623214, 49729.25406118911, 49731.0, 49786.0, 49944.0, 43600.0, 43792.562074379486, 44095.33856992148, 44121.55878441239, 44251.33622995658, 37519.437208441894, 39099.609003909594, 39151.608483914795, 39326.80336598299, 39974.20051598952, 8256.628451719567, 8262.710805122033, 8345.54099524532, 8925.910740892508, 8984.730458086267, 30267.848660756554, 30503.389932201677, 31244.375112498077, 31293.530597041517, 32557.0, 39081.80459097686, 39358.0, 39672.0, 39878.60121398749, 40103.79948100241, 10289.382637041801, 10382.636607718694, 10531.473426328499, 10629.8937010632, 10671.039606435457, 10763.300385474919, 10976.890231097586, 11067.391293478937, 11175.385353805294, 11640.650780476599, 10037.849432258376, 10601.1519078472, 10827.566897324334, 11069.944650276697, 11078.279911805706, 10521.368717876645, 10640.84038739404, 10668.30656007357, 10715.732106697395, 10729.248952573245, 11031.22781405294, 11191.160662950475, 11198.16013798952, 11213.383263920563, 11251.943740281245, 9098.863517047117, 9151.816963660822, 9230.215431688383, 9471.431714097176, 9494.430334179971, 9030.413023153475, 9058.773530661787, 9117.817643647222, 9270.768230794283, 9287.34988550822], "unit": "ms", "name": "yui.html", "value": 5715.1070462085645}], "name": "dromaeo_css", "value": 6331.135275600575}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 106.0, 114.0, 113.0, 113.0, 125.0, 111.0, 112.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 112.8}, {"lowerIsBetter": true, "replicates": [157.0, 137.0, 154.0, 169.0, 130.0, 155.0, 157.0, 138.0, 171.0, 138.0], "unit": "ms", "name": "audio-beat-detection", "value": 150.6}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 162.0, 164.0, 162.0, 165.0, 170.0, 161.0, 163.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 163.7}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 74.0, 72.0, 83.0, 75.0, 138.0, 72.0, 72.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.3}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 128.0, 94.0, 97.0, 95.0, 143.0, 95.0, 95.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 106.7}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 148.0, 147.0, 149.0, 148.0, 149.0, 147.0, 149.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.8}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 97.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [74.0, 75.0, 73.0, 72.0, 75.0, 73.0, 77.0, 72.0, 73.0, 81.0], "unit": "ms", "name": "json-parse-financial", "value": 74.5}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 68.0, 71.0, 68.0, 68.0, 68.0, 67.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [93.0, 84.0, 86.0, 85.0, 84.0, 84.0, 87.0, 86.0, 84.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.7}, {"lowerIsBetter": true, "replicates": [136.0, 122.0, 123.0, 125.0, 135.0, 129.0, 128.0, 122.0, 132.0, 130.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 128.2}, {"lowerIsBetter": true, "replicates": [147.0, 154.0, 155.0, 146.0, 149.0, 139.0, 146.0, 147.0, 151.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 147.2}, {"lowerIsBetter": true, "replicates": [70.0, 73.0, 69.0, 69.0, 69.0, 70.0, 73.0, 69.0, 69.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.1}], "name": "kraken", "value": 1541.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.89250165892503], "unit": "score", "name": "Richards", "value": 212.80045599999988}, {"lowerIsBetter": false, "replicates": [186.9508319312021], "unit": "score", "name": "DeltaBlue", "value": 353.66518199999996}, {"lowerIsBetter": false, "replicates": [276.8549280177187], "unit": "score", "name": "Encrypt", "value": 961.4457720000003}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [1071.8113612004288], "unit": "score", "name": "RayTrace", "value": 690.4097369999998}, {"lowerIsBetter": false, "replicates": [750.1875468867216], "unit": "score", "name": "Earley", "value": 888.3951789999996}, {"lowerIsBetter": false, "replicates": [12243.90243902439], "unit": "score", "name": "Boyer", "value": 54.4322370517928}, {"lowerIsBetter": false, "replicates": [26947.36842105263], "unit": "score", "name": "RegExp", "value": 33.80608398437501}, {"lowerIsBetter": false, "replicates": [593.8242280285035], "unit": "score", "name": "Splay", "value": 137.23084400000005}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 21223.81241277186}]} 04:29:41 INFO - RETURN: dromaeo_css: 6331.14 04:29:41 INFO - RETURN: kraken: 1541.60 04:29:41 INFO - RETURN: v8_7: 21223.81 04:29:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21223.81"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1541.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6331.14"}}} 04:29:41 INFO - Return code: 0 04:29:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:29:41 INFO - # TBPL SUCCESS # 04:29:41 INFO - Running post-action listener: _resource_record_post_action 04:29:41 INFO - Running post-run listener: _resource_record_post_run 04:29:42 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 8192; Write bytes: 518950912; Read time: 36; Write time: 457880 04:29:42 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 238399488; Read time: 20; Write time: 245652 04:29:42 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 4096; Write bytes: 276541440; Read time: 16; Write time: 211728 04:29:42 INFO - Running post-run listener: _upload_blobber_files 04:29:42 INFO - Blob upload gear active. 04:29:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:29:42 INFO - Copying logs to upload dir... 04:29:42 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1265.820644 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-12-14 04:29:40.561152) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-14 04:29:40.565019) ========= 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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010156 build_url: 'https://queue.taskcluster.net/v1/task/2NQ69-ULQ1CAR88N_0lw1w/artifacts/public/build/firefox-45.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-14 04:29:40.617668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:29:40.618199) ========= 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=919420d7ee4ef147bef0346300000385-1450094420.837090-1098035027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 04:29:40.670394) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-14 04:29:40.670951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-14 04:29:40.671487) ========= ========= Total master_lag: 0.25 =========