builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-013 starttime: 1450182637.62 results: warnings (1) buildid: 20151215030512 builduid: f0cbc7221a204f9885cb4fd16853d8ec revision: 7a22b7a21f27e5adc8d71f40d6035c2402e0a636 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.616287) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.616964) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.617622) ========= 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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.004386 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.661050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.661397) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.736430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.736758) ========= 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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004590 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.801611) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.801914) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.802365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:37.802681) ========= 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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-15 04:30:37-- 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% 227M=0s 2015-12-15 04:30:38 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.306635 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:38.144626) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:38.145042) ========= 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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.281624 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:38.452447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:38.452856) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7a22b7a21f27e5adc8d71f40d6035c2402e0a636 --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 7a22b7a21f27e5adc8d71f40d6035c2402e0a636 --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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-15 04:30:38,468 truncating revision to first 12 chars 2015-12-15 04:30:38,468 Setting DEBUG logging. 2015-12-15 04:30:38,468 attempt 1/10 2015-12-15 04:30:38,468 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7a22b7a21f27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-15 04:30:38,806 unpacking tar archive at: mozilla-central-7a22b7a21f27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621074 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:39.087811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:39.088245) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:39.101056) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:39.101380) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 04:30:39.102133) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' warnings (results: 1, elapsed: 23 mins, 25 secs) (at 2015-12-15 04:30:39.102497) ========= /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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:30:39 INFO - MultiFileLogger online at 20151215 04:30:39 in /builds/slave/test 04:30:39 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:30:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:30:39 INFO - {'append_to_log': False, 04:30:39 INFO - 'base_work_dir': '/builds/slave/test', 04:30:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:30:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:30:39 INFO - 'branch': 'Firefox-Non-PGO', 04:30:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:30:39 INFO - 'config_files': ('talos/linux_config.py',), 04:30:39 INFO - 'default_actions': ('clobber', 04:30:39 INFO - 'read-buildbot-config', 04:30:39 INFO - 'download-and-extract', 04:30:39 INFO - 'populate-webroot', 04:30:39 INFO - 'create-virtualenv', 04:30:39 INFO - 'install', 04:30:39 INFO - 'run-tests'), 04:30:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:30:39 INFO - 'download_minidump_stackwalk': True, 04:30:39 INFO - 'download_symbols': 'ondemand', 04:30:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:30:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:30:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:30:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:30:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:30:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:30:39 INFO - 'installer_path': 'installer.exe', 04:30:39 INFO - 'log_level': 'info', 04:30:39 INFO - 'log_name': 'talos', 04:30:39 INFO - 'log_to_console': True, 04:30:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:30:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:30:39 INFO - 'opt_config_files': (), 04:30:39 INFO - 'pip_index': False, 04:30:39 INFO - 'sps_profile': False, 04:30:39 INFO - 'sps_profile_interval': 0, 04:30:39 INFO - 'suite': 'dromaeojs-e10s', 04:30:39 INFO - 'system_bits': '64', 04:30:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:30:39 INFO - 'title': 'talos-linux64-ix-013', 04:30:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:30:39 INFO - 'use_talos_json': True, 04:30:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:30:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:30:39 INFO - 'work_dir': 'build'} 04:30:39 INFO - ##### 04:30:39 INFO - ##### Running clobber step. 04:30:39 INFO - ##### 04:30:39 INFO - Running pre-action listener: _resource_record_pre_action 04:30:39 INFO - Running main action method: clobber 04:30:39 INFO - rmtree: /builds/slave/test/build 04:30:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:30:40 INFO - Running post-action listener: _resource_record_post_action 04:30:40 INFO - ##### 04:30:40 INFO - ##### Running read-buildbot-config step. 04:30:40 INFO - ##### 04:30:40 INFO - Running pre-action listener: _resource_record_pre_action 04:30:40 INFO - Running main action method: read_buildbot_config 04:30:40 INFO - Using buildbot properties: 04:30:40 INFO - { 04:30:40 INFO - "properties": { 04:30:40 INFO - "buildnumber": 171, 04:30:40 INFO - "product": "firefox", 04:30:40 INFO - "script_repo_revision": "production", 04:30:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:30:40 INFO - "repository": "", 04:30:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:30:40 INFO - "buildid": "20151215030512", 04:30:40 INFO - "slavename": "talos-linux64-ix-013", 04:30:40 INFO - "pgo_build": "False", 04:30:40 INFO - "basedir": "/builds/slave/test", 04:30:40 INFO - "project": "", 04:30:40 INFO - "platform": "ubuntu64_hw", 04:30:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:30:40 INFO - "slavebuilddir": "test", 04:30:40 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:30:40 INFO - "branch": "mozilla-central", 04:30:40 INFO - "repo_path": "mozilla-central", 04:30:40 INFO - "stage_platform": "linux64", 04:30:40 INFO - "builduid": "f0cbc7221a204f9885cb4fd16853d8ec", 04:30:40 INFO - "revision": "7a22b7a21f27e5adc8d71f40d6035c2402e0a636" 04:30:40 INFO - }, 04:30:40 INFO - "sourcestamp": { 04:30:40 INFO - "repository": "", 04:30:40 INFO - "hasPatch": false, 04:30:40 INFO - "project": "", 04:30:40 INFO - "branch": "mozilla-central-linux64-talos", 04:30:40 INFO - "changes": [ 04:30:40 INFO - { 04:30:40 INFO - "category": null, 04:30:40 INFO - "files": [ 04:30:40 INFO - { 04:30:40 INFO - "url": null, 04:30:40 INFO - "name": "https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 04:30:40 INFO - } 04:30:40 INFO - ], 04:30:40 INFO - "repository": "", 04:30:40 INFO - "rev": "7a22b7a21f27e5adc8d71f40d6035c2402e0a636", 04:30:40 INFO - "who": "cbook@mozilla.com", 04:30:40 INFO - "when": 1450182624, 04:30:40 INFO - "number": 7361742, 04:30:40 INFO - "comments": "merge fx-team to mozilla-central a=merge", 04:30:40 INFO - "project": "", 04:30:40 INFO - "at": "Tue 15 Dec 2015 04:30:24", 04:30:40 INFO - "branch": "mozilla-central-linux64-talos", 04:30:40 INFO - "revlink": "", 04:30:40 INFO - "properties": [ 04:30:40 INFO - [ 04:30:40 INFO - "buildid", 04:30:40 INFO - "20151215030512", 04:30:40 INFO - "Change" 04:30:40 INFO - ], 04:30:40 INFO - [ 04:30:40 INFO - "builduid", 04:30:40 INFO - "f0cbc7221a204f9885cb4fd16853d8ec", 04:30:40 INFO - "Change" 04:30:40 INFO - ], 04:30:40 INFO - [ 04:30:40 INFO - "pgo_build", 04:30:40 INFO - "False", 04:30:40 INFO - "Change" 04:30:40 INFO - ] 04:30:40 INFO - ], 04:30:40 INFO - "revision": "7a22b7a21f27e5adc8d71f40d6035c2402e0a636" 04:30:40 INFO - } 04:30:40 INFO - ], 04:30:40 INFO - "revision": "7a22b7a21f27e5adc8d71f40d6035c2402e0a636" 04:30:40 INFO - } 04:30:40 INFO - } 04:30:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 04:30:40 INFO - Running post-action listener: _resource_record_post_action 04:30:40 INFO - ##### 04:30:40 INFO - ##### Running download-and-extract step. 04:30:40 INFO - ##### 04:30:40 INFO - Running pre-action listener: _resource_record_pre_action 04:30:40 INFO - Running main action method: download_and_extract 04:30:40 INFO - mkdir: /builds/slave/test/build/tests 04:30: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:30:40 INFO - https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:30:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/test_packages.json 04:30:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/test_packages.json 04:30:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:30:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:30:41 INFO - Downloaded 1302 bytes. 04:30:41 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:30:41 INFO - Using the following test package requirements: 04:30:41 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:30:41 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:30:41 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'jsshell-linux-x86_64.zip'], 04:30:41 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:30:41 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:30:41 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:30:41 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:30:41 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:30:41 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:30:41 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:30:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:30:41 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:41 INFO - https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:30:42 INFO - Downloaded 21869726 bytes. 04:30:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:30:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:30:45 INFO - Return code: 0 04:30:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:30: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:30:45 INFO - https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:30:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:30:59 INFO - Downloaded 21869726 bytes. 04:30:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:30:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:30:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:31:28 INFO - Return code: 0 04:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:28 INFO - https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:31:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:31:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:31:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:31:29 INFO - Downloaded 11132038 bytes. 04:31:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:31:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:31:29 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:31:29 INFO - Return code: 0 04:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:29 INFO - https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:31:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:31:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:31:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:31:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:31:32 INFO - Downloaded 57743688 bytes. 04:31:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:31:32 INFO - mkdir: /builds/slave/test/properties 04:31:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:31:32 INFO - Writing to file /builds/slave/test/properties/build_url 04:31:32 INFO - Contents: 04:31:32 INFO - build_url:https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:31:32 INFO - Running post-action listener: _resource_record_post_action 04:31:32 INFO - Running post-action listener: set_extra_try_arguments 04:31:32 INFO - ##### 04:31:32 INFO - ##### Running populate-webroot step. 04:31:32 INFO - ##### 04:31:32 INFO - Running pre-action listener: _resource_record_pre_action 04:31:32 INFO - Running main action method: populate_webroot 04:31:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:31:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:31:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:31:32 INFO - '--tppagecycles', 04:31:32 INFO - '7'], 04:31:32 INFO - 'tests': ['tsvgm']}}, 04:31:32 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:31:32 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:31:32 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:31:32 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:31:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'tests': ['damp', 'tps']}, 04:31:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['damp', 'tps']}, 04:31:32 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:31:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:31:32 INFO - 'other': {'tests': ['a11yr', 04:31:32 INFO - 'ts_paint', 04:31:32 INFO - 'tpaint', 04:31:32 INFO - 'sessionrestore', 04:31:32 INFO - 'sessionrestore_no_auto_restore']}, 04:31:32 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['a11yr', 04:31:32 INFO - 'ts_paint', 04:31:32 INFO - 'tpaint', 04:31:32 INFO - 'sessionrestore', 04:31:32 INFO - 'sessionrestore_no_auto_restore']}, 04:31:32 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['a11yr', 04:31:32 INFO - 'ts_paint', 04:31:32 INFO - 'tpaint', 04:31:32 INFO - 'sessionrestore', 04:31:32 INFO - 'sessionrestore_no_auto_restore']}, 04:31:32 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['a11yr', 04:31:32 INFO - 'ts_paint', 04:31:32 INFO - 'tpaint', 04:31:32 INFO - 'sessionrestore', 04:31:32 INFO - 'sessionrestore_no_auto_restore']}, 04:31:32 INFO - 'other_l64': {'tests': ['a11yr', 04:31:32 INFO - 'ts_paint', 04:31:32 INFO - 'tpaint', 04:31:32 INFO - 'sessionrestore', 04:31:32 INFO - 'sessionrestore_no_auto_restore']}, 04:31:32 INFO - 'other_nol64': {'tests': ['a11yr', 04:31:32 INFO - 'ts_paint', 04:31:32 INFO - 'tpaint', 04:31:32 INFO - 'sessionrestore', 04:31:32 INFO - 'sessionrestore_no_auto_restore']}, 04:31:32 INFO - 'svgr': {'tests': ['tsvgx', 04:31:32 INFO - 'tsvgr_opacity', 04:31:32 INFO - 'tart', 04:31:32 INFO - 'tscrollx', 04:31:32 INFO - 'cart']}, 04:31:32 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['tsvgx', 04:31:32 INFO - 'tsvgr_opacity', 04:31:32 INFO - 'tart', 04:31:32 INFO - 'tscrollx', 04:31:32 INFO - 'cart']}, 04:31:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'tests': ['tp5o']}, 04:31:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'talos_options': ['--e10s'], 04:31:32 INFO - 'tests': ['tp5o']}, 04:31:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'talos_options': ['--xperf_path', 04:31:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:32 INFO - 'tests': ['tp5n']}, 04:31:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:32 INFO - 'talos_options': ['--e10s', 04:31:32 INFO - '--xperf_path', 04:31:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:32 INFO - 'tests': ['tp5n']}}, 04:31:32 INFO - 'talos.zip': {'path': '', 04:31:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:31:32 INFO - Running post-action listener: _resource_record_post_action 04:31:32 INFO - ##### 04:31:32 INFO - ##### Running create-virtualenv step. 04:31:32 INFO - ##### 04:31:32 INFO - Running pre-action listener: _resource_record_pre_action 04:31:32 INFO - Running main action method: create_virtualenv 04:31:32 INFO - Creating virtualenv /builds/slave/test/build/venv 04:31:32 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:31:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:31:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:31:33 INFO - Using real prefix '/usr' 04:31:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:32:19 INFO - Installing distribute.............................................................................................................................................................................................done. 04:32:25 INFO - Installing pip.................done. 04:32:25 INFO - Return code: 0 04:32:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:25 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:25 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:32:25 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:32:25 INFO - Using env: {'DISPLAY': ':0', 04:32:25 INFO - 'HOME': '/home/cltbld', 04:32:25 INFO - 'LANG': 'en_US.UTF-8', 04:32:25 INFO - 'LANGUAGE': 'en_US:en', 04:32:25 INFO - 'LOGNAME': 'cltbld', 04:32:25 INFO - 'MAIL': '/var/mail/cltbld', 04:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:25 INFO - 'MOZ_NO_REMOTE': '1', 04:32:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:25 INFO - 'NO_EM_RESTART': '1', 04:32:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:25 INFO - 'PWD': '/builds/slave/test', 04:32:25 INFO - 'SHELL': '/bin/bash', 04:32:25 INFO - 'SHLVL': '1', 04:32:25 INFO - 'TERM': 'linux', 04:32:25 INFO - 'TMOUT': '86400', 04:32:25 INFO - 'USER': 'cltbld', 04:32:25 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:25 INFO - '_': '/tools/buildbot/bin/python'} 04:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:27 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:32:27 INFO - Cleaning up... 04:32:27 INFO - Return code: 0 04:32:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:27 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:27 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:32:27 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:32:27 INFO - Using env: {'DISPLAY': ':0', 04:32:27 INFO - 'HOME': '/home/cltbld', 04:32:27 INFO - 'LANG': 'en_US.UTF-8', 04:32:27 INFO - 'LANGUAGE': 'en_US:en', 04:32:27 INFO - 'LOGNAME': 'cltbld', 04:32:27 INFO - 'MAIL': '/var/mail/cltbld', 04:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:27 INFO - 'MOZ_NO_REMOTE': '1', 04:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:27 INFO - 'NO_EM_RESTART': '1', 04:32:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:27 INFO - 'PWD': '/builds/slave/test', 04:32:27 INFO - 'SHELL': '/bin/bash', 04:32:27 INFO - 'SHLVL': '1', 04:32:27 INFO - 'TERM': 'linux', 04:32:27 INFO - 'TMOUT': '86400', 04:32:27 INFO - 'USER': 'cltbld', 04:32:27 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:27 INFO - '_': '/tools/buildbot/bin/python'} 04:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:27 INFO - Downloading/unpacking psutil>=0.7.1 04:32:27 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:32:27 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:32:27 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:32:27 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:32:27 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:32:27 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:32:29 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:32:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:32:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:29 INFO - Installing collected packages: psutil 04:32:29 INFO - Running setup.py install for psutil 04:32:29 INFO - building 'psutil._psutil_linux' extension 04:32:29 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:32:33 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:32:33 INFO - building 'psutil._psutil_posix' extension 04:32:33 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:32:33 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:32:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:33 INFO - Successfully installed psutil 04:32:33 INFO - Cleaning up... 04:32:33 INFO - Return code: 0 04:32:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:32:33 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:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:33 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:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:33 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:33 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:32:33 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:32:33 INFO - Using env: {'DISPLAY': ':0', 04:32:33 INFO - 'HOME': '/home/cltbld', 04:32:33 INFO - 'LANG': 'en_US.UTF-8', 04:32:33 INFO - 'LANGUAGE': 'en_US:en', 04:32:33 INFO - 'LOGNAME': 'cltbld', 04:32:33 INFO - 'MAIL': '/var/mail/cltbld', 04:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:33 INFO - 'MOZ_NO_REMOTE': '1', 04:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:33 INFO - 'NO_EM_RESTART': '1', 04:32:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:33 INFO - 'PWD': '/builds/slave/test', 04:32:33 INFO - 'SHELL': '/bin/bash', 04:32:33 INFO - 'SHLVL': '1', 04:32:33 INFO - 'TERM': 'linux', 04:32:33 INFO - 'TMOUT': '86400', 04:32:33 INFO - 'USER': 'cltbld', 04:32:33 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:33 INFO - '_': '/tools/buildbot/bin/python'} 04:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:32:33 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:32:33 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:32:33 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:32:33 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:32:33 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:32:33 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:32:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:32:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:32:35 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:32:35 INFO - Installing collected packages: mozsystemmonitor 04:32:35 INFO - Running setup.py install for mozsystemmonitor 04:32:35 INFO - Successfully installed mozsystemmonitor 04:32:35 INFO - Cleaning up... 04:32:35 INFO - Return code: 0 04:32:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:32:35 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:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:35 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:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:35 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:35 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:32:35 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:32:35 INFO - Using env: {'DISPLAY': ':0', 04:32:35 INFO - 'HOME': '/home/cltbld', 04:32:35 INFO - 'LANG': 'en_US.UTF-8', 04:32:35 INFO - 'LANGUAGE': 'en_US:en', 04:32:35 INFO - 'LOGNAME': 'cltbld', 04:32:35 INFO - 'MAIL': '/var/mail/cltbld', 04:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:35 INFO - 'MOZ_NO_REMOTE': '1', 04:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:35 INFO - 'NO_EM_RESTART': '1', 04:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:35 INFO - 'PWD': '/builds/slave/test', 04:32:35 INFO - 'SHELL': '/bin/bash', 04:32:35 INFO - 'SHLVL': '1', 04:32:35 INFO - 'TERM': 'linux', 04:32:35 INFO - 'TMOUT': '86400', 04:32:35 INFO - 'USER': 'cltbld', 04:32:35 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:35 INFO - '_': '/tools/buildbot/bin/python'} 04:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:36 INFO - Downloading/unpacking blobuploader==1.2.4 04:32:36 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:32:36 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:32:36 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:32:36 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:32:36 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:32:36 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:32:37 INFO - Downloading blobuploader-1.2.4.tar.gz 04:32:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:32:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:32:37 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:32:37 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:32:37 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:32:37 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:32:37 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:32:37 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:32:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:32:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:32:38 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:32:38 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:32:38 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:32:38 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:32:38 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:32:38 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:32:38 INFO - Downloading docopt-0.6.1.tar.gz 04:32:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:32:38 INFO - Installing collected packages: blobuploader, requests, docopt 04:32:38 INFO - Running setup.py install for blobuploader 04:32:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:32:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:32:38 INFO - Running setup.py install for requests 04:32:38 INFO - Running setup.py install for docopt 04:32:38 INFO - Successfully installed blobuploader requests docopt 04:32:38 INFO - Cleaning up... 04:32:39 INFO - Return code: 0 04:32:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:39 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:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:39 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:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:39 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:39 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:32:39 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:32:39 INFO - Using env: {'DISPLAY': ':0', 04:32:39 INFO - 'HOME': '/home/cltbld', 04:32:39 INFO - 'LANG': 'en_US.UTF-8', 04:32:39 INFO - 'LANGUAGE': 'en_US:en', 04:32:39 INFO - 'LOGNAME': 'cltbld', 04:32:39 INFO - 'MAIL': '/var/mail/cltbld', 04:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:39 INFO - 'MOZ_NO_REMOTE': '1', 04:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:39 INFO - 'NO_EM_RESTART': '1', 04:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:39 INFO - 'PWD': '/builds/slave/test', 04:32:39 INFO - 'SHELL': '/bin/bash', 04:32:39 INFO - 'SHLVL': '1', 04:32:39 INFO - 'TERM': 'linux', 04:32:39 INFO - 'TMOUT': '86400', 04:32:39 INFO - 'USER': 'cltbld', 04:32:39 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:39 INFO - '_': '/tools/buildbot/bin/python'} 04:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:39 INFO - Running setup.py (path:/tmp/pip-nFw881-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:39 INFO - Running setup.py (path:/tmp/pip-0s4nGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:39 INFO - Running setup.py (path:/tmp/pip-il7saM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:39 INFO - Running setup.py (path:/tmp/pip-hdMPfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:39 INFO - Running setup.py (path:/tmp/pip-8er9or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:39 INFO - Running setup.py (path:/tmp/pip-CjWHzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:39 INFO - Running setup.py (path:/tmp/pip-GZ19jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:39 INFO - Running setup.py (path:/tmp/pip-igaHlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:39 INFO - Running setup.py (path:/tmp/pip-jVWtUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:39 INFO - Running setup.py (path:/tmp/pip-zjx4bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:39 INFO - Running setup.py (path:/tmp/pip-Hu_I27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:40 INFO - Running setup.py (path:/tmp/pip-_iOC9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:40 INFO - Running setup.py (path:/tmp/pip-diyeH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:40 INFO - Running setup.py (path:/tmp/pip-CXsgB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:40 INFO - Running setup.py (path:/tmp/pip-rJKK0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:40 INFO - Running setup.py (path:/tmp/pip-4J6DxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:40 INFO - Running setup.py (path:/tmp/pip-6iVbSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:32:40 INFO - Running setup.py install for manifestparser 04:32:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:32:40 INFO - Running setup.py install for mozcrash 04:32:40 INFO - Running setup.py install for mozdebug 04:32:40 INFO - Running setup.py install for mozdevice 04:32:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:32:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:32:40 INFO - Running setup.py install for mozfile 04:32:40 INFO - Running setup.py install for mozhttpd 04:32:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for mozinfo 04:32:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for mozInstall 04:32:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for mozleak 04:32:41 INFO - Running setup.py install for mozlog 04:32:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for moznetwork 04:32:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for mozprocess 04:32:41 INFO - Running setup.py install for mozprofile 04:32:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for mozrunner 04:32:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:32:41 INFO - Running setup.py install for mozscreenshot 04:32:41 INFO - Running setup.py install for moztest 04:32:42 INFO - Running setup.py install for mozversion 04:32:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:32:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:32:42 INFO - Cleaning up... 04:32:42 INFO - Return code: 0 04:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:42 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:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:42 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:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:42 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:42 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:32:42 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:32:42 INFO - Using env: {'DISPLAY': ':0', 04:32:42 INFO - 'HOME': '/home/cltbld', 04:32:42 INFO - 'LANG': 'en_US.UTF-8', 04:32:42 INFO - 'LANGUAGE': 'en_US:en', 04:32:42 INFO - 'LOGNAME': 'cltbld', 04:32:42 INFO - 'MAIL': '/var/mail/cltbld', 04:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:42 INFO - 'MOZ_NO_REMOTE': '1', 04:32:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:42 INFO - 'NO_EM_RESTART': '1', 04:32:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:42 INFO - 'PWD': '/builds/slave/test', 04:32:42 INFO - 'SHELL': '/bin/bash', 04:32:42 INFO - 'SHLVL': '1', 04:32:42 INFO - 'TERM': 'linux', 04:32:42 INFO - 'TMOUT': '86400', 04:32:42 INFO - 'USER': 'cltbld', 04:32:42 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:42 INFO - '_': '/tools/buildbot/bin/python'} 04:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:42 INFO - Running setup.py (path:/tmp/pip-2moaFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:42 INFO - Running setup.py (path:/tmp/pip-c5mrxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:42 INFO - Running setup.py (path:/tmp/pip-k_wdgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:42 INFO - Running setup.py (path:/tmp/pip-Dfh4Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:42 INFO - Running setup.py (path:/tmp/pip-fRVx5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:42 INFO - Running setup.py (path:/tmp/pip-4VFoKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:42 INFO - Running setup.py (path:/tmp/pip-ScoeyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:42 INFO - Running setup.py (path:/tmp/pip-lJqSl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:42 INFO - Running setup.py (path:/tmp/pip-p2SSGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:43 INFO - Running setup.py (path:/tmp/pip-jEpC3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:43 INFO - Running setup.py (path:/tmp/pip-IMewHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:43 INFO - Running setup.py (path:/tmp/pip-7hvDiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:43 INFO - Running setup.py (path:/tmp/pip-FQvcNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:43 INFO - Running setup.py (path:/tmp/pip-qNNU_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:43 INFO - Running setup.py (path:/tmp/pip-TImlbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:43 INFO - Running setup.py (path:/tmp/pip-WN3Feo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:43 INFO - Running setup.py (path:/tmp/pip-9Z5IG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:43 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:32:43 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:32:43 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:32:43 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:32:43 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:32:43 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:32:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:32:43 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:32:43 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:32:43 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:32:43 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:32:43 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:32:43 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:32:45 INFO - Downloading blessings-1.5.1.tar.gz 04:32:45 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:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:32:45 INFO - Installing collected packages: blessings 04:32:45 INFO - Running setup.py install for blessings 04:32:46 INFO - Successfully installed blessings 04:32:46 INFO - Cleaning up... 04:32:46 INFO - Return code: 0 04:32:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:32:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:46 INFO - Reading from file tmpfile_stdout 04:32:46 INFO - Current package versions: 04:32:46 INFO - argparse == 1.2.1 04:32:46 INFO - blessings == 1.5.1 04:32:46 INFO - blobuploader == 1.2.4 04:32:46 INFO - docopt == 0.6.1 04:32:46 INFO - manifestparser == 1.1 04:32:46 INFO - mozInstall == 1.12 04:32:46 INFO - mozcrash == 0.16 04:32:46 INFO - mozdebug == 0.1 04:32:46 INFO - mozdevice == 0.47 04:32:46 INFO - mozfile == 1.2 04:32:46 INFO - mozhttpd == 0.7 04:32:46 INFO - mozinfo == 0.9 04:32:46 INFO - mozleak == 0.1 04:32:46 INFO - mozlog == 3.1 04:32:46 INFO - moznetwork == 0.27 04:32:46 INFO - mozprocess == 0.22 04:32:46 INFO - mozprofile == 0.27 04:32:46 INFO - mozrunner == 6.11 04:32:46 INFO - mozscreenshot == 0.1 04:32:46 INFO - mozsystemmonitor == 0.0 04:32:46 INFO - moztest == 0.7 04:32:46 INFO - mozversion == 1.4 04:32:46 INFO - psutil == 3.1.1 04:32:46 INFO - requests == 1.2.3 04:32:46 INFO - wsgiref == 0.1.2 04:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:46 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:32:46 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:32:46 INFO - Using env: {'DISPLAY': ':0', 04:32:46 INFO - 'HOME': '/home/cltbld', 04:32:46 INFO - 'LANG': 'en_US.UTF-8', 04:32:46 INFO - 'LANGUAGE': 'en_US:en', 04:32:46 INFO - 'LOGNAME': 'cltbld', 04:32:46 INFO - 'MAIL': '/var/mail/cltbld', 04:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:46 INFO - 'MOZ_NO_REMOTE': '1', 04:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:46 INFO - 'NO_EM_RESTART': '1', 04:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:46 INFO - 'PWD': '/builds/slave/test', 04:32:46 INFO - 'SHELL': '/bin/bash', 04:32:46 INFO - 'SHLVL': '1', 04:32:46 INFO - 'TERM': 'linux', 04:32:46 INFO - 'TMOUT': '86400', 04:32:46 INFO - 'USER': 'cltbld', 04:32:46 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:46 INFO - '_': '/tools/buildbot/bin/python'} 04:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 INFO - Cleaning up... 04:32:46 INFO - Return code: 0 04:32:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:46 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:32:46 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:32:46 INFO - Using env: {'DISPLAY': ':0', 04:32:46 INFO - 'HOME': '/home/cltbld', 04:32:46 INFO - 'LANG': 'en_US.UTF-8', 04:32:46 INFO - 'LANGUAGE': 'en_US:en', 04:32:46 INFO - 'LOGNAME': 'cltbld', 04:32:46 INFO - 'MAIL': '/var/mail/cltbld', 04:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:46 INFO - 'MOZ_NO_REMOTE': '1', 04:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:46 INFO - 'NO_EM_RESTART': '1', 04:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:46 INFO - 'PWD': '/builds/slave/test', 04:32:46 INFO - 'SHELL': '/bin/bash', 04:32:46 INFO - 'SHLVL': '1', 04:32:46 INFO - 'TERM': 'linux', 04:32:46 INFO - 'TMOUT': '86400', 04:32:46 INFO - 'USER': 'cltbld', 04:32:46 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:46 INFO - '_': '/tools/buildbot/bin/python'} 04:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:46 INFO - Downloading/unpacking jsonschema 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:32:48 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:32:48 INFO - Downloading/unpacking functools32 (from jsonschema) 04:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:48 INFO - Downloading functools32-3.2.3-2.tar.gz 04:32:48 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:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:32:48 INFO - warning: no files found matching '*.txt' 04:32:48 INFO - no previously-included directories found matching 'build' 04:32:48 INFO - no previously-included directories found matching 'dist' 04:32:48 INFO - no previously-included directories found matching '.git*' 04:32:48 INFO - Installing collected packages: jsonschema, functools32 04:32:48 INFO - Running setup.py install for functools32 04:32:48 INFO - warning: no files found matching '*.txt' 04:32:48 INFO - no previously-included directories found matching 'build' 04:32:48 INFO - no previously-included directories found matching 'dist' 04:32:48 INFO - no previously-included directories found matching '.git*' 04:32:48 INFO - Successfully installed jsonschema functools32 04:32:48 INFO - Cleaning up... 04:32:48 INFO - Return code: 0 04:32:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:48 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 0x7f9f872d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b5f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, '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': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', '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:32:48 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:32:48 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:32:48 INFO - Using env: {'DISPLAY': ':0', 04:32:48 INFO - 'HOME': '/home/cltbld', 04:32:48 INFO - 'LANG': 'en_US.UTF-8', 04:32:48 INFO - 'LANGUAGE': 'en_US:en', 04:32:48 INFO - 'LOGNAME': 'cltbld', 04:32:48 INFO - 'MAIL': '/var/mail/cltbld', 04:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:48 INFO - 'MOZ_NO_REMOTE': '1', 04:32:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:48 INFO - 'NO_EM_RESTART': '1', 04:32:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:48 INFO - 'PWD': '/builds/slave/test', 04:32:48 INFO - 'SHELL': '/bin/bash', 04:32:48 INFO - 'SHLVL': '1', 04:32:48 INFO - 'TERM': 'linux', 04:32:48 INFO - 'TMOUT': '86400', 04:32:48 INFO - 'USER': 'cltbld', 04:32:48 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:48 INFO - '_': '/tools/buildbot/bin/python'} 04:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:49 INFO - Downloading/unpacking flake8 04:32: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:32: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:32: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:32: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:32: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:32: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:32:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:32:50 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:32:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:32:50 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:32:50 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:32:50 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:32:50 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:32:50 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:32:50 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:32:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:32:51 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:32:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:51 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:32:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:32:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:32:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:32:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:32:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:32:51 INFO - no previously-included directories found matching 'docs/_build' 04:32:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:32:51 INFO - Running setup.py install for pep8 04:32:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:32:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:32:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:32:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:32:51 INFO - no previously-included directories found matching 'docs/_build' 04:32:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:32:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:32:51 INFO - Cleaning up... 04:32:51 INFO - Return code: 0 04:32:51 INFO - Running post-action listener: _resource_record_post_action 04:32:51 INFO - Running post-action listener: _start_resource_monitoring 04:32:51 INFO - Starting resource monitoring. 04:32:51 INFO - ##### 04:32:51 INFO - ##### Running install step. 04:32:51 INFO - ##### 04:32:51 INFO - Running pre-action listener: _resource_record_pre_action 04:32:51 INFO - Running main action method: install 04:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:52 INFO - Reading from file tmpfile_stdout 04:32:52 INFO - Detecting whether we're running mozinstall >=1.0... 04:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:32:52 INFO - Reading from file tmpfile_stdout 04:32:52 INFO - Output received: 04:32:52 INFO - Usage: mozinstall [options] installer 04:32:52 INFO - Options: 04:32:52 INFO - -h, --help show this help message and exit 04:32:52 INFO - -d DEST, --destination=DEST 04:32:52 INFO - Directory to install application into. [default: 04:32:52 INFO - "/builds/slave/test"] 04:32:52 INFO - --app=APP Application being installed. [default: firefox] 04:32:52 INFO - mkdir: /builds/slave/test/build/application 04:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:33:06 INFO - Reading from file tmpfile_stdout 04:33:06 INFO - Output received: 04:33:06 INFO - /builds/slave/test/build/application/firefox/firefox 04:33:06 INFO - Running post-action listener: _resource_record_post_action 04:33:06 INFO - ##### 04:33:06 INFO - ##### Running run-tests step. 04:33:06 INFO - ##### 04:33:06 INFO - Running pre-action listener: _resource_record_pre_action 04:33:06 INFO - Running main action method: run_tests 04:33:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:33:06 INFO - Python 2.7.3 04:33:06 INFO - Return code: 0 04:33:06 INFO - grabbing minidump binary from tooltool 04:33:06 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:33:06 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 0x2bee0b0>, '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 0x2beefb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bef440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:33:06 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:33:06 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:33:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:33:06 INFO - Return code: 0 04:33:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:33:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:33:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:33:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:33:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:33:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:33:07 INFO - Return code: 0 04:33:07 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/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost'] in /builds/slave/test/build 04:33:07 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/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost 04:33:07 INFO - Using env: {'DISPLAY': ':0', 04:33:07 INFO - 'HOME': '/home/cltbld', 04:33:07 INFO - 'LANG': 'en_US.UTF-8', 04:33:07 INFO - 'LANGUAGE': 'en_US:en', 04:33:07 INFO - 'LOGNAME': 'cltbld', 04:33:07 INFO - 'MAIL': '/var/mail/cltbld', 04:33:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:33:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:07 INFO - 'MOZ_NO_REMOTE': '1', 04:33:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:33:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:33:07 INFO - 'NO_EM_RESTART': '1', 04:33:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:07 INFO - 'PWD': '/builds/slave/test', 04:33:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:33:07 INFO - 'SHELL': '/bin/bash', 04:33:07 INFO - 'SHLVL': '1', 04:33:07 INFO - 'TERM': 'linux', 04:33:07 INFO - 'TMOUT': '86400', 04:33:07 INFO - 'USER': 'cltbld', 04:33:07 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210', 04:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:07 INFO - '_': '/tools/buildbot/bin/python'} 04:33:07 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/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost'] with output_timeout 3600 04:33:07 INFO - mozversion INFO | application_buildid: 20151215030512 04:33:07 INFO - mozversion INFO | application_changeset: 7a22b7a21f27e5adc8d71f40d6035c2402e0a636 04:33:07 INFO - mozversion INFO | application_display_name: Nightly 04:33:07 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:33:07 INFO - mozversion INFO | application_name: Firefox 04:33:07 INFO - mozversion INFO | application_remotingname: firefox 04:33:07 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:33:07 INFO - mozversion INFO | application_vendor: Mozilla 04:33:07 INFO - mozversion INFO | application_version: 46.0a1 04:33:07 INFO - mozversion INFO | platform_buildid: 20151215030512 04:33:07 INFO - mozversion INFO | platform_changeset: 7a22b7a21f27e5adc8d71f40d6035c2402e0a636 04:33:07 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:33:07 INFO - mozversion INFO | platform_version: 46.0a1 04:33:07 INFO - 2015-12-15 04:33:07,966 DEBUG : using testdate: 1450182787 04:33:07 INFO - 2015-12-15 04:33:07,966 DEBUG : actual date: 1450182787 04:33:07 INFO - 2015-12-15 04:33:07,976 INFO : starting webserver on 'localhost:58168' 04:33:07 INFO - 2015-12-15 04:33:07,976 INFO : Starting test suite talos-linux64-ix-013.e 04:33:07 INFO - 2015-12-15 04:33:07,977 INFO : Starting test dromaeo_css 04:33:07 INFO - 2015-12-15 04:33:07,977 DEBUG : operating with platform_type : linux_ 04:33:07 INFO - 2015-12-15 04:33:07,977 INFO : Initialising browser for dromaeo_css test... 04:33:08 INFO - 2015-12-15 04:33:08,015 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8lnb8R/profile http://localhost:58168/getInfo.html 04:33:55 INFO - 2015-12-15 04:33:55,436 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:33:55 INFO - 2015-12-15 04:33:55,436 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:33:55 INFO - 2015-12-15 04:33:55,437 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:33:55 INFO - 2015-12-15 04:33:55,437 DEBUG : BROWSER_OUTPUT: __metrics 04:33:56 INFO - 2015-12-15 04:33:56,581 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:33:56 INFO - 2015-12-15 04:33:56,900 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 04:34:00 INFO - 2015-12-15 04:34:00,769 INFO : Browser initialized. 04:34:00 INFO - 2015-12-15 04:34:00,770 INFO : Running cycle 1/1 for dromaeo_css test... 04:34:00 INFO - 2015-12-15 04:34:00,770 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8lnb8R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:34:12 INFO - 2015-12-15 04:34:12,813 DEBUG : BROWSER_OUTPUT: RSS: Main: 142618624 04:34:12 INFO - 2015-12-15 04:34:12,813 DEBUG : BROWSER_OUTPUT: 04:35:02 INFO - 2015-12-15 04:35:02,437 DEBUG : BROWSER_OUTPUT: 1450182902432 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:37:14 INFO - 2015-12-15 04:37:14,612 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58168/tests/dromaeo/cssquery-ext.html) 04:37:15 INFO - 2015-12-15 04:37:15,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 149884928 04:37:15 INFO - 2015-12-15 04:37:15,657 DEBUG : BROWSER_OUTPUT: 04:40:17 INFO - 2015-12-15 04:40:17,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/dromaeo/cssquery-ext.html (next: http://localhost:58168/tests/dromaeo/cssquery-jquery.html) 04:40:18 INFO - 2015-12-15 04:40:18,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 144023552 04:40:18 INFO - 2015-12-15 04:40:18,276 DEBUG : BROWSER_OUTPUT: 04:43:20 INFO - 2015-12-15 04:43:20,032 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58168/tests/dromaeo/cssquery-mootools.html) 04:43:20 INFO - 2015-12-15 04:43:20,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 145117184 04:43:20 INFO - 2015-12-15 04:43:20,625 DEBUG : BROWSER_OUTPUT: 04:46:22 INFO - 2015-12-15 04:46:22,405 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58168/tests/dromaeo/cssquery-prototype.html) 04:46:23 INFO - 2015-12-15 04:46:23,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 144056320 04:46:23 INFO - 2015-12-15 04:46:23,007 DEBUG : BROWSER_OUTPUT: 04:49:25 INFO - 2015-12-15 04:49:25,658 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58168/tests/dromaeo/cssquery-yui.html) 04:49:26 INFO - 2015-12-15 04:49:26,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 145014784 04:49:26 INFO - 2015-12-15 04:49:26,287 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,570 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/dromaeo/cssquery-yui.html (next: http://localhost:58168/tests/dromaeo/cssquery-dojo.html) 04:52:28 INFO - 2015-12-15 04:52:28,820 DEBUG : BROWSER_OUTPUT: RSS: Main: 147406848 04:52:28 INFO - 2015-12-15 04:52:28,820 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,821 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:52:28 INFO - 2015-12-15 04:52:28,822 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:52:28 INFO - 2015-12-15 04:52:28,822 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:52:28 INFO - 2015-12-15 04:52:28,822 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:52:28 INFO - 2015-12-15 04:52:28,825 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2644.7223041580633;2667.359833639927;2699.8110132290735;2704.364474348529;2715.0497325935926;4634.4902060773375;4732.10090082884;4736.597389221912;4739.052189562089;4778.211595086811;4687.859364219079;4762.309465127563;4788.569028787409;4803.087413391462;4813.470518242999;4259.275923093066;4318.762468064255;4379;4398.746357288173;4431.335299705038;4441.089576636782;4459.063596644693;4468.97765511172;4484.865454036384;4553.180427523061;4207.726497777635;4308.633766129883;4457.064016556527;4476.395686582302;4524.8190072397065;4292.7424354538825;4331.393604895317;4334.674899382559;4354.608085272341;4372.540883207281;4343;4375.2124617568825;4377.474703035624;4386.451693538308;4387.561243875619;4478.619317358029;4588.197065513522;4639.6752227344095;4690.0854333405;4698.107359601699;5127.154019586764;5141.794328226866;5155.40712818027;5170.948290517084;5172.689638621657;5145.742712864342;5157.329547158883;5161.303224064778;5168.664036837594;5204.791808327663;4590.862274131781;4627.537246275346;4720.386349774576;4760.7619619018915;4798.664093513456;5189.6886186828915;5209.166533354645;5214.348206474191;5230.660007099526;5249.370075590878;4971.477994810565;4976;4987.202047672355;4996.875078123076;5010.64925455222;4920.8769780755765;5016.297718319438;5019.67372120811;5020.322256495399;5033.219850923113;4897.755112244374;4952.133376659142;4955.677880937799;5011.072951503984;5069.315642388304;5045.924311135336;5073.974630126826;5077.974610126926;5083.313752643346;5124.9743751281;4091.9386209206887;4240.151969606091;4240.257954857888;4250.383694364362;4293.720908140961;3660.853565857336;3675.191457879262;3686.9262614747554;3713.9814300928865;3741.7193710471824;4434.600885920283;4438.4451943507065;4441.0673758511075;4450;4475.194464996332;4953.6532442729385;5099.158638824552;5156.793728250903;5179.507946745053;5236.816711415082;4375.365571992043;4376.518582955872;4388.968592380788;4392.604665580113;4397.0985947881245;4501.977490112528;4511.05267893746;4513.277875539898;4538.387317712132;4556.863294101182;4783.258594917794;4784.712917224978;4792.808287668454;4843.515648435128;4846.466888642317;4906.681065730716;4972.328735620645;5015.949840501694;5019.824306149268;5117.181250999822;4743.407074115736;4770.165221086227;4858.611311095175;4869.342638743724;4884.8290309838985;5009.77456014473;5105.259737337991;5139.331886854685;5141.177411614124;5152.30443890085;4823.589994850477;4940.110780059624;5020.221865610836;5203.661761985459;5235.476452354734;5010.348654674869;5033.421156567042;5043.899122017612;5048.040872234411;5049.671771334852;4945.2582112683385;4985.401751789808;4993;5003.599712022957;5031.4213865405945;4638.1187574360765;4734.242521196592;4744.857654270378;4781.641376896816;4995.500449954975;5009.899802004013;5013.222950442687;5055.974720126376;5059.418166910852;5104.2088476286235;4876.780544875418;4900.387451568554;4909.361782968192;4933.457319694759;4943.159662857251 04:52:28 INFO - 2015-12-15 04:52:28,826 DEBUG : BROWSER_OUTPUT: |1;ext.html;550.1787842669845;578.3262499188446;584.0363400389358;585.5930128560651;586.1237450012233;35917.640823591755;36507.81746091272;36613;36660;36733.44899826501;40249.39625905606;40741.18517629646;40762.796186019026;40778.59221407785;40816.18367632653;7862.174471680477;8055.919440805605;8057.879131813028;8076.919230807675;8082.151374105722;7790.298873101419;7825.921740782576;7885.369170466349;7890.3293220076075;7977.202279772035;8014.959925200365;8043.839123217562;8055.395845311596;8107.02715674117;8113.797155071112;7981.640826162808;8088.878666820003;8110.878336824952;8122.065962414339;8132.064812546574;7927.484713493604;8069.515829050276;8093.069297030858;8105.392095592854;8115.918840811575;37893.62106378929;38208.617913821065;38258.617413825785;38466.80766596177;38497.23005539901;2822.9717702822913;2850.6151669524556;2946.6611339695996;3221.178599457147;3410.0963244740165;28067.71932280692;28124.156275311772;28205.43589128206;28330.716692833015;28439.289017774514;3177.875374702487;3220.45252307106;3225.8387080645875;3299.340131973615;3331.283773988604;210659;211647;212282;214772;214839;51389.7430512845;53034.20448693273;54044;54361;61836.38163618396;56471.71764141153;56653.716731416076;56687.43312566904;56803.71598141983;57197;12773.808392874114;12901.161424507378;12905.612831615066;12936.935315323362;13165.47338106465;9331.906680933103;9333.346665733468;9356.298277629205;9361.063893610584;9383.671571494964;19267.51831204231;19736.605267894563;20016.49958751043;20031.198752050048;20039;7639.465237433326;7837.647305871249;7847.882281765778;7931.762047138595;8000.399970002273;750.0848964263698;811.7280710961894;823.6828820903995;903.1916434790826;942.2085448223526;23643.7635623645;23843.523129537658;24028.51942961131;24072.398190045038;24137.7586224139;921.5162039929091;928.9303302252223;992.771662517625;1025.779457416659;1041;12913.935430322976;12961.870381296256;13074.215547067206;13133.737325253442;13237.470501179845;274832;274847;276494;278310;278328;46487.535124648995;46642.76678616653;46700.53299466962;46763.53236467592;46808.29787553189;1173.976520469603;1179.7699448607611;1183.2545496337425;1193.6956076200513;1215.574548907893;23951.76048239495;24385;24401;24800.875995619906;24843.751562484144;39330.60669393269;39783.403248951865;39824.60175398209;39867.80066099651;39908.600913990485;257670;259364;259750;259869;260999;28833.855830720713;29062.564061539517;29188;29332;29725;33980.66019339775;34934.82532587321;37017.6298237025;37060.81469592635;37366.813165934;31707.365852683277;32373.19067023343;32531.674683253812;33236.50145247872;33304.83347583246;31912.68087319097;32464.513032305007;32598.837005814818;32810.343793123524;32951.835240823646;32914;33392.332153356314;33962.66037339595;34088.82955585206;34128.31743365168;26045.479090417706;27558.5866212003;27823.443531128858;28108.578371324038;29472.263193420335;23092.307230783103;23638.172663957368;25080.874595626905;25711.871440643426;25885.352866178495 04:52:28 INFO - 2015-12-15 04:52:28,829 DEBUG : BROWSER_OUTPUT: |2;jquery.html;329.9472084466485;331.35055291628413;336.7608997611696;339.1639608365379;341.2169071979806;14969;15309.770353444706;15795.36818527259;16053.839461605381;16842.073685947267;9578.616855325796;9669.37149085309;9709.805803883917;11811.99598034168;15072.2463876806;4556.612687921531;4668.4631267404175;4677.321788340689;4678.485366609671;5018.974905125469;5159.922601160967;5174.379074511064;5183.688978661293;5185.948140518603;5194.142966410556;4957.132501812186;5116.360454943132;5118.795248190068;5130.179171332587;5169.974150129263;5038.874028149289;5042.470540593258;5086.618503612244;5088.898222035557;5115.923261151086;4994.700317980933;5061.139606266944;5072.695638261679;5095.464976177522;5101.030804147237;11169.273997190157;11439.256448330914;13971.65070873226;18579.349722759638;18918.148683309286;1740.416960318295;1757.551824284812;1772.4682595221382;1790.97313540297;1867.6918308479085;8750.124987501262;8812.8678069829;8964.775880602972;9003.414778039472;9160.312976526788;1738.8869723467933;1759.9384021559183;1910.2263583248669;1964.557974455736;2014.546726986431;55518.722406388115;56843.147352789325;56984;58014;58026.41973580295;9921.851172232422;10019.348742331726;10054.547545360476;10077.647282345082;10128.493575321205;12416.192947458387;12446.31545265001;12475.438605262785;12501.812472812915;12709.809352859715;6808.319168083152;6827.453803695792;6848.212455567574;6870.690818913161;6948.756793512202;6631.436327912085;6657.1345725055435;6683.064370988066;6745.9662701686175;6819.215790184192;4155.293600087993;4212.831486740496;4230.682698797603;4248.4052232687445;4260.126674031816;1748.8863223890407;1787.356551641408;1792.6414717056641;1817.9637606564395;1838.0534024977148;721.2967356827052;733.2887099513464;770.9730159444392;813.8494378539989;905.3752910491739;15494.5351639451;15758.76361854573;15766.9211653941;15873.206339682969;16021.519354419153;794.408165916396;814.3281792521194;839.706102864005;852.26705033671;859.5186695450568;9021.458712477272;9168.495732734764;9171.945226298927;9252.213561847184;9274.350795444254;472358;479028;488552;490282;494696;73398.26601734021;74266.25733742594;74355.88466173012;75523;75587.62206188934;1000.4347543637822;1020.3469779341227;1023.81059503992;1024.4365598920565;1045.4824861693357;14494.34775435129;14640.797687460328;14719.632009199855;14747.2626368679;14845.40618375253;32648.673513264563;33404.164895877795;33948.83025584955;34201.82899085488;34554.65445345615;42810;43160.78419608007;44839.55160448354;44941.32588011117;47017.764911175225;9035.774105647411;9070.18368346852;9080.091990800867;9112.681056163268;9119.179273865384;15683.607909801889;15783.28975196142;16552.83447165513;16580.253888574804;16677;13215.933920330337;13837.377318020512;14049.859501404855;14134.08128471646;14187.290635467978;13206.405711742802;13633.590992270249;13851.445942162201;14272.928635356757;14889.925550372178;14100.858991409954;14325.283735813377;14466.638334041732;14638;15165.848341516885;8769.47383156987;8959.059298773534;9169.174774270348;9339.766505837408;9368.906310936803;13844.169349839041;14375.209363485108;14401.135931844123;14469.348879300247;14627.488037918622 04:52:28 INFO - 2015-12-15 04:52:28,829 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1365.8497565267826;1390.311795661148;1435.633913352095;1647.192875491009;1731.1776905969662;6005.519558435326;6101.664408457545;6487.286398496161;6874.312568743123;6874.793756187316;6401.935980640193;6466.159399278087;6594.802155935317;6604.801855944329;6616.676664667062;1937.883726976386;1937.9321723739672;1959.4807376045287;1980.1683293016968;1981.603679264153;1974.684050551912;1988.2444671024991;1990.8905010224432;2051.9897400512978;2056.362527616436;1969.3894892583346;1983.1274239334646;2005.00752127697;2006.939791806241;2010;1942.0289855072465;1954.0132233222273;1954.1499447740277;1954.8631595788302;1956.814102660252;1934.3713293179774;1935.0808366026165;1977.7725561560317;2044.7444069491314;2055.640262953977;6610.636414997173;6707.3628005339415;6717.462602991798;6731.798046058626;6733.663316834188;3898.922021559553;3961.564227934925;3966.603339666039;3980.6019398060253;3999.060220848098;2506.1729629222496;2553.1957433408415;2563.282280961334;2564.4486435416475;2567.7817385522253;803.5620586780219;811.2455416462687;813.5850716134755;838.5010918503481;841.3185319890908;8511.574421278974;8623.568821558896;8625.870611940825;8633.784155396166;8741.125887411208;3615.1142969971997;3627.6372362763514;3650.361186792301;3683.208110256308;3692.0585250760882;7554.924450755532;7576.772696819105;7602.7339043133225;7607.733729319447;7611.695532178762;3818.6372294631965;3907.1794923065904;3920.843166273374;3922.1763429680086;3940.014996250937;3528.0826984984087;3535.0101971448034;3561.3411518869093;3561.394562924257;3597.31650986317;3097.3650401667605;3105.1771280610656;3109.113902537766;3169.2710676544525;3173.3811906677975;942.2838642631649;945.6406565505064;948.6063283737171;949.3449519831295;950.4202436513721;818.1164342510075;875.3172525430175;1166.2652528906735;1172.5485688010178;1201.0991756182864;6339.207599050119;6342.904856427157;6365.204349456318;6367.904481432783;6381.8723625528155;1037.7094413564214;1044.1072882685194;1062.123747907979;1062.9202809789297;1066.8879767624444;4745;4847.103285892122;4854;4864.440589332201;4900.485449027872;7822.687092516236;7919.604019799102;8004.479708818908;8131.9593402032615;8188.50868947865;7839.529628222325;8008.879866801886;8030.718924837603;8044.477108987897;8085.231902969209;6257.530685198628;6262.467690246289;6364.268109167412;6370.203724534434;6379.5215358847345;6091.725872335667;6112.633242005494;6117.694115294306;6154.1999540059505;6175.84560385994;6923.446124310144;7064.258252883374;7102.431805455448;7152.821179470555;7179.353858152583;7026.578405295494;7090.397316228178;7160.856782864418;7181.748638797617;7196.424286056998;5563.415841336601;5598.944010559842;5646.604737668324;5978.730957106854;6176.073588961692;6362.109304697438;6404.9039264412;6459.128017717548;6467;6490.350964903472;6035.426634469718;6209.906851397323;6296.716647750954;6345.968270158804;6359.20509936258;5828.417158284137;5931.051031834886;5989.041753319448;6027.427394397525;6028.819135425944;5716.28546431696;5966.433188847052;5995.46040856325;6025.939740602713;6054.636721796706;5665.461781130786;5746.712664366682;5834.38738932406;5849.61977471463;5858.765649373977;5648.039833228443;5684.317881854204;5739.770409183586;5782.190493331022;5922.052471604523 04:52:28 INFO - 2015-12-15 04:52:28,833 DEBUG : BROWSER_OUTPUT: |4;prototype.html;290.7644807705758;297.1560767420525;301.31901901702156;304.08470503783604;306.4698072334862;5463.399026107125;5556.083246264367;5589.580781441398;5600.103983362657;5616.6630002199845;5399.838004859851;5548.472895074961;5551.72241387931;5551.805686800963;5577.721113944307;4273.166732487166;4278.294081476568;4279.165562715264;4282.957170428287;4288.249556327646;4278.887489252786;4282.657387409015;4287.464066991626;4295.828166873322;4300.78496075195;4057;4148.958510414887;4253.8723838284905;4255.0426154115385;4263.936040959388;4145.2124096421585;4149.377593361005;4153.273177193994;4160.188763191179;4160.43834082398;3579.821008949542;3589.1565482111682;3595.083253770298;3598.9100272493133;3603.3153700797025;5187.0922588546855;5270.736463176826;5284;5306.336707911511;5347.7058761768085;4702.224133018048;4786.305985632099;4802.1596220661595;4820.132376172288;4827.324174615556;5584.5811564132855;5687.232223649796;5708.600397972102;5726.656400615976;5783.248177736909;3881.4954055972544;3916.236333914888;3934.5475270343704;3947.881563553098;3952.565217826037;5989.730462129215;6117.96941015292;6118.1434599156155;6124.969375153096;6143.78496752616;3928.941065884014;4060.4315395844606;4071.3485842265095;4087.0395457067566;4089.54821038533;4140.627343539096;4140.730852494578;4144.046869220097;4144.91710165795;4145.66834653227;4790.377250957354;4801.807927682854;4801.927971080437;4801.975990120097;4804.5195480451675;5733;5772.9711351442975;5778.942210577925;5793.478586927113;5812.825615231465;1377.373295150724;1430.4778755754076;1432.2838580709645;1462.9926850365894;1464.0922627970513;939.2767568971854;966.2270183852889;968.0222974795366;985.8866230383454;987.7975015121883;4996.625253106032;5000.849974500771;5001.44984051754;5008.398992120896;5023.974880125576;5924.081767325985;5924.081767326072;5951.553633477506;5981.342052374235;5994.190784244159;4996.250562415594;5003.174476211457;5008.1486147355035;5011.649184557046;5012.298278241049;3179.1257404213566;3198.424283628969;3234.951475727866;3236.2394837213237;3296.126526470487;5744.770209191585;5769.596128271065;5770.509506691894;5782.98797710399;5785.768569257182;6263.123162757127;6318;6320.652364120017;6322.336154703782;6325.746970121236;5631.155326701028;5768.2501274834;5775.826725198251;5779.450952159538;5784.219130417424;6089.330173680803;6090.086487026982;6091.177691011657;6092.111445451924;6097.7865774697675;5956.463918247378;5957.582969192115;5961.701914904151;5965;5966.492848107786;5716.742746576331;5739.684317362585;5742;5748.827535173951;5759.7408116634015;3942.684585233117;4015.738976966488;4037.9798101009305;4058.573849745734;4062.370332598452;5815.912761308499;6090.725917333642;6094.902917474719;6102.9694851525455;6129.172561704113;5714.085746280742;5736.885262294647;5815.9709201453725;5831.212786273799;5847.239858818326;5771.451712087345;5792.681402522901;5828.417158284137;5831.1544826000845;5877.500412464818;5576.7211639417055;5691.601587888808;5704.11586204139;5708.372079071216;5717.685527295872;5913.822585322448;5925.377835327228;5928.970355148197;5934;5935.940640593712;5755.5971082025535;5767.68277744728;5770.769169233184;5777.162311464898;5793.536517078708 04:52:28 INFO - 2015-12-15 04:52:28,834 DEBUG : BROWSER_OUTPUT: |5;yui.html;594.3284088979452;601.9819405417837;723.1466869094467;730.9046189387943;740.311510295425;11825.7634847303;12004.339761313146;12048.57829975951;12068.75862482751;12085.093617978642;12347.135700500947;12541.247525148498;12613.990880729543;12807.679808004805;12919.870801291985;795.5544894858898;815.1278132398336;827.664795757719;829.0465964141256;829.1459796409678;806.435495153395;807.7818988873;811.059171361221;813.7436707437139;814.9755507334789;840.9243168114868;845.6194712379454;845.6828689241535;846.4455781463121;849.8045449546609;836.1304243586662;836.4479443567277;836.564986207172;838.4172999765166;838.4927119092953;609.1624016976657;620.9223847019123;625.0374423387977;625.377749139609;625.627751487864;12020.759584808342;12134.757304853854;12137.21108127969;12164.51341946321;12166.87833121675;10598.417087060208;11023.393713345762;11097.16771242152;11407.771844563062;11670.708232294175;9238.214751746109;9440.622375104987;9493.905060949372;9521;9805.558749856202;3205.5672484214565;3242.1084201844683;3277.901662950115;3294.621118571371;3299.6040475143136;42611.14777704429;42743.78628106871;42951.78524107391;43136.137277254595;43184;8978.326625503114;8980.595873185723;9086.363954523253;9124.224440922464;9237.445753254826;15833;15904.204789760466;16284.022958622521;16311.102889340962;16327.591810204602;6259.593126446766;6462.806115816533;6490.837729056717;6600.735970561123;6624.8343791405605;3204.599425071866;3353.983230083834;3367.1582104473882;3370.443876760307;3450.6721861423125;2516.219971808745;2542.567763480176;2560.577504711739;2562.6284188792893;2564.2563656539396;482.31992890024895;485.20426500539145;512.3365242011604;523.071548002299;525.0732457213022;438.5395334898408;439.8592450415836;532.707011143877;533.9839804805862;592.8458600763746;7010.789676309719;7276.7089316426745;7462.865596449505;7530.548167109991;7736.497127686683;475.1731986343806;541.5207541325876;562.8142712904731;573.6443405088788;609.1989034419812;3390.813505257185;3459.8789042383396;3564.1624218308675;3612.9819350903076;3793.6016718244186;48376.27435588469;48440.75779621079;48572.514274856796;51198.74400627973;51966.22050669243;42742.78628606837;43940;44378.33432498515;44383.11233775307;45200.547994519635;39922.60077399247;40686.18627627431;41020.38469422961;41298.58701413008;41438.79280603638;7620.314171724572;7657.387409007378;7700.114486834086;7733.072031356048;7944.126146124037;31827;31900.202494937814;31950.68049319477;32231.194220144684;32360.83819580887;36547.63452365442;36652.45021324629;38259.42610860783;40431.79784101061;40558;9662.516874156405;9862.408255504406;10193.082622564005;10297.227707921786;10299.176065914859;10987.230893837355;11057.170712196457;11121.555137794398;11201;11551.884481155417;10134.847977280495;10449.477526123512;10493.632722854663;10834.349939003685;11110.388928609003;10661.680149595524;10758.408287543947;10866.18503612248;10917.781644366909;10948.124150067817;10469.738256543647;10470.738231544272;10563.94718026405;10766.084882785051;10934.234603577674;8964;9006.774830629287;9292.814143717223;9335.346525743404;9606.327557070937;8763.956180219313;8905.777355565904;9056.320775941962;9073.59168837417;9076.455412675261 04:52:28 INFO - 2015-12-15 04:52:28,834 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:52:28 INFO - 2015-12-15 04:52:28,834 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:52:28 INFO - 2015-12-15 04:52:28,834 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,349 04:52:28 INFO - 2015-12-15 04:52:28,834 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: __startTimestamp1450183948821__endTimestamp 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4697.00 Median:4803.09 stddev:498.65 (10.4%) stddev-sans-first:473.48 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: Values: 2644.7 2667.4 2699.8 2704.4 2715.0 4634.5 4732.1 4736.6 4739.1 4778.2 4687.9 4762.3 4788.6 4803.1 4813.5 4259.3 4318.8 4379.0 4398.7 4431.3 4441.1 4459.1 4469.0 4484.9 4553.2 4207.7 4308.6 4457.1 4476.4 4524.8 4292.7 4331.4 4334.7 4354.6 4372.5 4343.0 4375.2 4377.5 4386.5 4387.6 4478.6 4588.2 4639.7 4690.1 4698.1 5127.2 5141.8 5155.4 5170.9 5172.7 5145.7 5157.3 5161.3 5168.7 5204.8 4590.9 4627.5 4720.4 4760.8 4798.7 5189.7 5209.2 5214.3 5230.7 5249.4 4971.5 4976.0 4987.2 4996.9 5010.6 4920.9 5016.3 5019.7 5020.3 5033.2 4897.8 4952.1 4955.7 5011.1 5069.3 5045.9 5074.0 5078.0 5083.3 5125.0 4091.9 4240.2 4240.3 4250.4 4293.7 3660.9 3675.2 3686.9 3714.0 3741.7 4434.6 4438.4 4441.1 4450.0 4475.2 4953.7 5099.2 5156.8 5179.5 5236.8 4375.4 4376.5 4389.0 4392.6 4397.1 4502.0 4511.1 4513.3 4538.4 4556.9 4783.3 4784.7 4792.8 4843.5 4846.5 4906.7 4972.3 5015.9 5019.8 5117.2 4743.4 4770.2 4858.6 4869.3 4884.8 5009.8 5105.3 5139.3 5141.2 5152.3 4823.6 4940.1 5020.2 5203.7 5235.5 5010.3 5033.4 5043.9 5048.0 5049.7 4945.3 4985.4 4993.0 5003.6 5031.4 4638.1 4734.2 4744.9 4781.6 4995.5 5009.9 5013.2 5056.0 5059.4 5104.2 4876.8 4900.4 4909.4 4933.5 4943.2 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40800.66 Median:18749.46 stddev:65518.84 (349.4%) stddev-sans-first:65632.94 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: Values: 550.2 578.3 584.0 585.6 586.1 35917.6 36507.8 36613.0 36660.0 36733.4 40249.4 40741.2 40762.8 40778.6 40816.2 7862.2 8055.9 8057.9 8076.9 8082.2 7790.3 7825.9 7885.4 7890.3 7977.2 8015.0 8043.8 8055.4 8107.0 8113.8 7981.6 8088.9 8110.9 8122.1 8132.1 7927.5 8069.5 8093.1 8105.4 8115.9 37893.6 38208.6 38258.6 38466.8 38497.2 2823.0 2850.6 2946.7 3221.2 3410.1 28067.7 28124.2 28205.4 28330.7 28439.3 3177.9 3220.5 3225.8 3299.3 3331.3 210659.0 211647.0 212282.0 214772.0 214839.0 51389.7 53034.2 54044.0 54361.0 61836.4 56471.7 56653.7 56687.4 56803.7 57197.0 12773.8 12901.2 12905.6 12936.9 13165.5 9331.9 9333.3 9356.3 9361.1 9383.7 19267.5 19736.6 20016.5 20031.2 20039.0 7639.5 7837.6 7847.9 7931.8 8000.4 750.1 811.7 823.7 903.2 942.2 23643.8 23843.5 24028.5 24072.4 24137.8 921.5 928.9 992.8 1025.8 1041.0 12913.9 12961.9 13074.2 13133.7 13237.5 274832.0 274847.0 276494.0 278310.0 278328.0 46487.5 46642.8 46700.5 46763.5 46808.3 1174.0 1179.8 1183.3 1193.7 1215.6 23951.8 24385.0 24401.0 24800.9 24843.8 39330.6 39783.4 39824.6 39867.8 39908.6 257670.0 259364.0 259750.0 259869.0 260999.0 28833.9 29062.6 29188.0 29332.0 29725.0 33980.7 34934.8 37017.6 37060.8 37366.8 31707.4 32373.2 32531.7 33236.5 33304.8 31912.7 32464.5 32598.8 32810.3 32951.8 32914.0 33392.3 33962.7 34088.8 34128.3 26045.5 27558.6 27823.4 28108.6 29472.3 23092.3 23638.2 25080.9 25711.9 25885.4 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,835 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26402.08 Median:23535.06 stddev:79298.01 (336.9%) stddev-sans-first:79496.30 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: Values: 329.9 331.4 336.8 339.2 341.2 14969.0 15309.8 15795.4 16053.8 16842.1 9578.6 9669.4 9709.8 11812.0 15072.2 4556.6 4668.5 4677.3 4678.5 5019.0 5159.9 5174.4 5183.7 5185.9 5194.1 4957.1 5116.4 5118.8 5130.2 5170.0 5038.9 5042.5 5086.6 5088.9 5115.9 4994.7 5061.1 5072.7 5095.5 5101.0 11169.3 11439.3 13971.7 18579.3 18918.1 1740.4 1757.6 1772.5 1791.0 1867.7 8750.1 8812.9 8964.8 9003.4 9160.3 1738.9 1759.9 1910.2 1964.6 2014.5 55518.7 56843.1 56984.0 58014.0 58026.4 9921.9 10019.3 10054.5 10077.6 10128.5 12416.2 12446.3 12475.4 12501.8 12709.8 6808.3 6827.5 6848.2 6870.7 6948.8 6631.4 6657.1 6683.1 6746.0 6819.2 4155.3 4212.8 4230.7 4248.4 4260.1 1748.9 1787.4 1792.6 1818.0 1838.1 721.3 733.3 771.0 813.8 905.4 15494.5 15758.8 15766.9 15873.2 16021.5 794.4 814.3 839.7 852.3 859.5 9021.5 9168.5 9171.9 9252.2 9274.4 472358.0 479028.0 488552.0 490282.0 494696.0 73398.3 74266.3 74355.9 75523.0 75587.6 1000.4 1020.3 1023.8 1024.4 1045.5 14494.3 14640.8 14719.6 14747.3 14845.4 32648.7 33404.2 33948.8 34201.8 34554.7 42810.0 43160.8 44839.6 44941.3 47017.8 9035.8 9070.2 9080.1 9112.7 9119.2 15683.6 15783.3 16552.8 16580.3 16677.0 13215.9 13837.4 14049.9 14134.1 14187.3 13206.4 13633.6 13851.4 14272.9 14889.9 14100.9 14325.3 14466.6 14638.0 15165.8 8769.5 8959.1 9169.2 9339.8 9368.9 13844.2 14375.2 14401.1 14469.3 14627.5 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4661.10 Median:5983.89 stddev:2370.27 (39.6%) stddev-sans-first:2363.98 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: Values: 1365.8 1390.3 1435.6 1647.2 1731.2 6005.5 6101.7 6487.3 6874.3 6874.8 6401.9 6466.2 6594.8 6604.8 6616.7 1937.9 1937.9 1959.5 1980.2 1981.6 1974.7 1988.2 1990.9 2052.0 2056.4 1969.4 1983.1 2005.0 2006.9 2010.0 1942.0 1954.0 1954.1 1954.9 1956.8 1934.4 1935.1 1977.8 2044.7 2055.6 6610.6 6707.4 6717.5 6731.8 6733.7 3898.9 3961.6 3966.6 3980.6 3999.1 2506.2 2553.2 2563.3 2564.4 2567.8 803.6 811.2 813.6 838.5 841.3 8511.6 8623.6 8625.9 8633.8 8741.1 3615.1 3627.6 3650.4 3683.2 3692.1 7554.9 7576.8 7602.7 7607.7 7611.7 3818.6 3907.2 3920.8 3922.2 3940.0 3528.1 3535.0 3561.3 3561.4 3597.3 3097.4 3105.2 3109.1 3169.3 3173.4 942.3 945.6 948.6 949.3 950.4 818.1 875.3 1166.3 1172.5 1201.1 6339.2 6342.9 6365.2 6367.9 6381.9 1037.7 1044.1 1062.1 1062.9 1066.9 4745.0 4847.1 4854.0 4864.4 4900.5 7822.7 7919.6 8004.5 8132.0 8188.5 7839.5 8008.9 8030.7 8044.5 8085.2 6257.5 6262.5 6364.3 6370.2 6379.5 6091.7 6112.6 6117.7 6154.2 6175.8 6923.4 7064.3 7102.4 7152.8 7179.4 7026.6 7090.4 7160.9 7181.7 7196.4 5563.4 5598.9 5646.6 5978.7 6176.1 6362.1 6404.9 6459.1 6467.0 6490.4 6035.4 6209.9 6296.7 6346.0 6359.2 5828.4 5931.1 5989.0 6027.4 6028.8 5716.3 5966.4 5995.5 6025.9 6054.6 5665.5 5746.7 5834.4 5849.6 5858.8 5648.0 5684.3 5739.8 5782.2 5922.1 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4798.48 Median:5566.40 stddev:1444.73 (26.0%) stddev-sans-first:1408.61 04:52:28 INFO - 2015-12-15 04:52:28,837 DEBUG : BROWSER_OUTPUT: Values: 290.8 297.2 301.3 304.1 306.5 5463.4 5556.1 5589.6 5600.1 5616.7 5399.8 5548.5 5551.7 5551.8 5577.7 4273.2 4278.3 4279.2 4283.0 4288.2 4278.9 4282.7 4287.5 4295.8 4300.8 4057.0 4149.0 4253.9 4255.0 4263.9 4145.2 4149.4 4153.3 4160.2 4160.4 3579.8 3589.2 3595.1 3598.9 3603.3 5187.1 5270.7 5284.0 5306.3 5347.7 4702.2 4786.3 4802.2 4820.1 4827.3 5584.6 5687.2 5708.6 5726.7 5783.2 3881.5 3916.2 3934.5 3947.9 3952.6 5989.7 6118.0 6118.1 6125.0 6143.8 3928.9 4060.4 4071.3 4087.0 4089.5 4140.6 4140.7 4144.0 4144.9 4145.7 4790.4 4801.8 4801.9 4802.0 4804.5 5733.0 5773.0 5778.9 5793.5 5812.8 1377.4 1430.5 1432.3 1463.0 1464.1 939.3 966.2 968.0 985.9 987.8 4996.6 5000.8 5001.4 5008.4 5024.0 5924.1 5924.1 5951.6 5981.3 5994.2 4996.3 5003.2 5008.1 5011.6 5012.3 3179.1 3198.4 3235.0 3236.2 3296.1 5744.8 5769.6 5770.5 5783.0 5785.8 6263.1 6318.0 6320.7 6322.3 6325.7 5631.2 5768.3 5775.8 5779.5 5784.2 6089.3 6090.1 6091.2 6092.1 6097.8 5956.5 5957.6 5961.7 5965.0 5966.5 5716.7 5739.7 5742.0 5748.8 5759.7 3942.7 4015.7 4038.0 4058.6 4062.4 5815.9 6090.7 6094.9 6103.0 6129.2 5714.1 5736.9 5816.0 5831.2 5847.2 5771.5 5792.7 5828.4 5831.2 5877.5 5576.7 5691.6 5704.1 5708.4 5717.7 5913.8 5925.4 5929.0 5934.0 5935.9 5755.6 5767.7 5770.8 5777.2 5793.5 04:52:28 INFO - 2015-12-15 04:52:28,838 DEBUG : BROWSER_OUTPUT: 04:52:28 INFO - 2015-12-15 04:52:28,838 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12302.73 Median:22189.93 stddev:13928.23 (62.8%) stddev-sans-first:13939.55 04:52:28 INFO - 2015-12-15 04:52:28,839 DEBUG : BROWSER_OUTPUT: Values: 594.3 602.0 723.1 730.9 740.3 11825.8 12004.3 12048.6 12068.8 12085.1 12347.1 12541.2 12614.0 12807.7 12919.9 795.6 815.1 827.7 829.0 829.1 806.4 807.8 811.1 813.7 815.0 840.9 845.6 845.7 846.4 849.8 836.1 836.4 836.6 838.4 838.5 609.2 620.9 625.0 625.4 625.6 12020.8 12134.8 12137.2 12164.5 12166.9 10598.4 11023.4 11097.2 11407.8 11670.7 9238.2 9440.6 9493.9 9521.0 9805.6 3205.6 3242.1 3277.9 3294.6 3299.6 42611.1 42743.8 42951.8 43136.1 43184.0 8978.3 8980.6 9086.4 9124.2 9237.4 15833.0 15904.2 16284.0 16311.1 16327.6 6259.6 6462.8 6490.8 6600.7 6624.8 3204.6 3354.0 3367.2 3370.4 3450.7 2516.2 2542.6 2560.6 2562.6 2564.3 482.3 485.2 512.3 523.1 525.1 438.5 439.9 532.7 534.0 592.8 7010.8 7276.7 7462.9 7530.5 7736.5 475.2 541.5 562.8 573.6 609.2 3390.8 3459.9 3564.2 3613.0 3793.6 48376.3 48440.8 48572.5 51198.7 51966.2 42742.8 43940.0 44378.3 44383.1 45200.5 39922.6 40686.2 41020.4 41298.6 41438.8 7620.3 7657.4 7700.1 7733.1 7944.1 31827.0 31900.2 31950.7 32231.2 32360.8 36547.6 36652.5 38259.4 40431.8 40558.0 9662.5 9862.4 10193.1 10297.2 10299.2 10987.2 11057.2 11121.6 11201.0 11551.9 10134.8 10449.5 10493.6 10834.3 11110.4 10661.7 10758.4 10866.2 10917.8 10948.1 10469.7 10470.7 10563.9 10766.1 10934.2 8964.0 9006.8 9292.8 9335.3 9606.3 8764.0 8905.8 9056.3 9073.6 9076.5 04:52:28 INFO - 2015-12-15 04:52:28,839 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:52:28 INFO - 2015-12-15 04:52:28,839 DEBUG : BROWSER_OUTPUT: 04:52:30 INFO - 2015-12-15 04:52:30,329 INFO : Browser exited with error code: 0 04:52:30 INFO - 2015-12-15 04:52:30,353 INFO : Completed test dromaeo_css (00:19:22) 04:52:30 INFO - 2015-12-15 04:52:30,353 INFO : Starting test kraken 04:52:30 INFO - 2015-12-15 04:52:30,353 DEBUG : operating with platform_type : linux_ 04:52:30 INFO - 2015-12-15 04:52:30,353 INFO : Initialising browser for kraken test... 04:52:30 INFO - 2015-12-15 04:52:30,359 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdONCcW/profile http://localhost:58168/getInfo.html 04:52:49 INFO - 2015-12-15 04:52:49,099 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:52:49 INFO - 2015-12-15 04:52:49,099 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:52:49 INFO - 2015-12-15 04:52:49,099 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:52:49 INFO - 2015-12-15 04:52:49,099 DEBUG : BROWSER_OUTPUT: __metrics 04:52:49 INFO - 2015-12-15 04:52:49,963 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:52:50 INFO - 2015-12-15 04:52:50,435 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 04:52:54 INFO - 2015-12-15 04:52:54,037 INFO : Browser initialized. 04:52:54 INFO - 2015-12-15 04:52:54,038 INFO : Running cycle 1/1 for kraken test... 04:52:54 INFO - 2015-12-15 04:52:54,038 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdONCcW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:53:05 INFO - 2015-12-15 04:53:05,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 142884864 04:53:05 INFO - 2015-12-15 04:53:05,995 DEBUG : BROWSER_OUTPUT: 04:53:08 INFO - 2015-12-15 04:53:08,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58168/tests/kraken/driver.html?testName=audio-beat-detection) 04:53:09 INFO - 2015-12-15 04:53:09,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 143523840 04:53:09 INFO - 2015-12-15 04:53:09,251 DEBUG : BROWSER_OUTPUT: 04:53:12 INFO - 2015-12-15 04:53:12,009 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58168/tests/kraken/driver.html?testName=audio-dft) 04:53:12 INFO - 2015-12-15 04:53:12,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 143597568 04:53:12 INFO - 2015-12-15 04:53:12,598 DEBUG : BROWSER_OUTPUT: 04:53:15 INFO - 2015-12-15 04:53:15,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58168/tests/kraken/driver.html?testName=audio-fft) 04:53:16 INFO - 2015-12-15 04:53:16,103 DEBUG : BROWSER_OUTPUT: RSS: Main: 137654272 04:53:16 INFO - 2015-12-15 04:53:16,103 DEBUG : BROWSER_OUTPUT: 04:53:18 INFO - 2015-12-15 04:53:18,160 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58168/tests/kraken/driver.html?testName=audio-oscillator) 04:53:18 INFO - 2015-12-15 04:53:18,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 138256384 04:53:18 INFO - 2015-12-15 04:53:18,741 DEBUG : BROWSER_OUTPUT: 04:53:20 INFO - 2015-12-15 04:53:20,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58168/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:53:21 INFO - 2015-12-15 04:53:21,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 139911168 04:53:21 INFO - 2015-12-15 04:53:21,584 DEBUG : BROWSER_OUTPUT: 04:53:24 INFO - 2015-12-15 04:53:24,634 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58168/tests/kraken/driver.html?testName=imaging-darkroom) 04:53:25 INFO - 2015-12-15 04:53:25,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 141430784 04:53:25 INFO - 2015-12-15 04:53:25,235 DEBUG : BROWSER_OUTPUT: 04:53:28 INFO - 2015-12-15 04:53:28,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58168/tests/kraken/driver.html?testName=imaging-desaturate) 04:53:29 INFO - 2015-12-15 04:53:29,234 DEBUG : BROWSER_OUTPUT: RSS: Main: 141344768 04:53:29 INFO - 2015-12-15 04:53:29,234 DEBUG : BROWSER_OUTPUT: 04:53:32 INFO - 2015-12-15 04:53:32,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58168/tests/kraken/driver.html?testName=json-parse-financial) 04:53:32 INFO - 2015-12-15 04:53:32,701 DEBUG : BROWSER_OUTPUT: RSS: Main: 139698176 04:53:32 INFO - 2015-12-15 04:53:32,701 DEBUG : BROWSER_OUTPUT: 04:53:34 INFO - 2015-12-15 04:53:34,475 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58168/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:53:35 INFO - 2015-12-15 04:53:35,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 141602816 04:53:35 INFO - 2015-12-15 04:53:35,064 DEBUG : BROWSER_OUTPUT: 04:53:36 INFO - 2015-12-15 04:53:36,933 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:53:37 INFO - 2015-12-15 04:53:37,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 140595200 04:53:37 INFO - 2015-12-15 04:53:37,542 DEBUG : BROWSER_OUTPUT: 04:53:39 INFO - 2015-12-15 04:53:39,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:53:40 INFO - 2015-12-15 04:53:40,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 140660736 04:53:40 INFO - 2015-12-15 04:53:40,319 DEBUG : BROWSER_OUTPUT: 04:53:42 INFO - 2015-12-15 04:53:42,866 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:53:43 INFO - 2015-12-15 04:53:43,467 DEBUG : BROWSER_OUTPUT: RSS: Main: 140996608 04:53:43 INFO - 2015-12-15 04:53:43,468 DEBUG : BROWSER_OUTPUT: 04:53:46 INFO - 2015-12-15 04:53:46,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:53:46 INFO - 2015-12-15 04:53:46,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 141787136 04:53:46 INFO - 2015-12-15 04:53:46,640 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,381 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58168/tests/kraken/driver.html?testName=ai-astar) 04:53:48 INFO - 2015-12-15 04:53:48,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 141205504 04:53:48 INFO - 2015-12-15 04:53:48,630 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |0;ai-astar;120;109;113;114;114;125;116;112;114;112 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;132;155;166;136;151;152;142;172;134 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;162;159;163;164;173;164;164;162 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;73;79;134;71;78;73;72;93 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;129;93;95;94;129;94;95;137 04:53:48 INFO - 2015-12-15 04:53:48,631 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;111;110;110;110;110;110 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;148;152;149;146;147;146;148;147;149 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;97;96;96;96;96;96;96;96 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;74;76;76;76;75;76;81;76;74 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;67;67;69;69;67;68;69;68 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;92;94;85;93;85;100;85;86;87;86 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;119;122;145;122;134;126;125;121;132 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;153;144;152;153;139;157;151;161;139 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;70;73;72;72;72;72;73;70;71 04:53:48 INFO - 2015-12-15 04:53:48,632 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:53:48 INFO - 2015-12-15 04:53:48,633 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:53:48 INFO - 2015-12-15 04:53:48,633 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,800 04:53:48 INFO - 2015-12-15 04:53:48,633 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:53:48 INFO - 2015-12-15 04:53:48,633 DEBUG : BROWSER_OUTPUT: __startTimestamp1450184028629__endTimestamp 04:53:48 INFO - 2015-12-15 04:53:48,633 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,636 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:53:48 INFO - 2015-12-15 04:53:48,636 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:53:48 INFO - 2015-12-15 04:53:48,636 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.90 Median:114.00 stddev:4.56 (4.0%) stddev-sans-first:4.44 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: Values: 120.0 109.0 113.0 114.0 114.0 125.0 116.0 112.0 114.0 112.0 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.50 Median:153.50 stddev:13.47 (8.8%) stddev-sans-first:14.14 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: Values: 155.0 132.0 155.0 166.0 136.0 151.0 152.0 142.0 172.0 134.0 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:3.63 (2.2%) stddev-sans-first:3.84 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 162.0 159.0 163.0 164.0 173.0 164.0 164.0 162.0 04:53:48 INFO - 2015-12-15 04:53:48,637 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.90 Median:73.00 stddev:19.42 (26.6%) stddev-sans-first:20.33 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 73.0 79.0 134.0 71.0 78.0 73.0 72.0 93.0 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:94.00 stddev:18.34 (19.5%) stddev-sans-first:18.90 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 129.0 93.0 95.0 94.0 129.0 94.0 95.0 137.0 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 111.0 110.0 110.0 110.0 110.0 110.0 04:53:48 INFO - 2015-12-15 04:53:48,638 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.87 (1.3%) stddev-sans-first:1.87 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: Values: 146.0 148.0 152.0 149.0 146.0 147.0 146.0 148.0 147.0 149.0 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:75.90 Median:76.00 stddev:1.97 (2.6%) stddev-sans-first:2.06 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 76.0 76.0 76.0 75.0 76.0 81.0 76.0 74.0 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,639 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 67.0 67.0 69.0 69.0 67.0 68.0 69.0 68.0 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:86.50 stddev:5.17 (6.0%) stddev-sans-first:5.39 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 85.0 93.0 85.0 100.0 85.0 86.0 87.0 86.0 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:125.50 stddev:8.26 (6.6%) stddev-sans-first:8.31 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: Values: 119.0 119.0 122.0 145.0 122.0 134.0 126.0 125.0 121.0 132.0 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,640 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.90 Median:152.50 stddev:7.88 (5.2%) stddev-sans-first:7.67 04:53:48 INFO - 2015-12-15 04:53:48,641 DEBUG : BROWSER_OUTPUT: Values: 140.0 153.0 144.0 152.0 153.0 139.0 157.0 151.0 161.0 139.0 04:53:48 INFO - 2015-12-15 04:53:48,641 DEBUG : BROWSER_OUTPUT: 04:53:48 INFO - 2015-12-15 04:53:48,641 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:72.00 stddev:1.07 (1.5%) stddev-sans-first:1.12 04:53:48 INFO - 2015-12-15 04:53:48,641 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 73.0 72.0 72.0 72.0 72.0 73.0 70.0 71.0 04:53:48 INFO - 2015-12-15 04:53:48,641 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:53:48 INFO - 2015-12-15 04:53:48,641 DEBUG : BROWSER_OUTPUT: 04:53:56 INFO - 2015-12-15 04:53:56,643 INFO : Browser shutdown timed out after 5 seconds, terminating process. 04:53:59 INFO - 2015-12-15 04:53:56,643 DEBUG : Terminating psutil.Process(pid=3883, name='firefox') 04:54:03 INFO - 2015-12-15 04:54:03,145 DEBUG : BROWSER_OUTPUT: [Child 3948] ###!!! ABORT: Aborting on channel error.: file /builds/slave/m-cen-l64-00000000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 1765 04:54:03 INFO - 2015-12-15 04:54:03,146 DEBUG : BROWSER_OUTPUT: [Child 3948] ###!!! ABORT: Aborting on channel error.: file /builds/slave/m-cen-l64-00000000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 1765 04:54:03 ERROR - Traceback (most recent call last): 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 241, in 04:54:03 INFO - main() 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 237, in main 04:54:03 INFO - sys.exit(run_tests(config, browser_config)) 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 195, in run_tests 04:54:03 INFO - talos_results.add(mytest.runTest(browser_config, test)) 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 61, in runTest 04:54:03 INFO - return self._runTest(browser_config, test_config, setup) 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 173, in _runTest 04:54:03 INFO - if counter_management else None), 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 122, in run_browser 04:54:03 INFO - context.kill_process() 04:54:03 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 36, in kill_process 04:54:03 INFO - self.process.wait(3) 04:54:03 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/__init__.py", line 1102, in wait 04:54:03 INFO - return self._proc.wait(timeout) 04:54:03 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 709, in wrapper 04:54:03 INFO - return fun(self, *args, **kwargs) 04:54:03 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 851, in wait 04:54:03 INFO - raise TimeoutExpired(timeout, self.pid, self._name) 04:54:03 INFO - psutil.TimeoutExpired: psutil.TimeoutExpired timeout after 3 seconds (pid=3883, name='firefox') 04:54:03 ERROR - Return code: 1 04:54:03 WARNING - setting return code to 1 04:54:03 ERROR - # TBPL WARNING # 04:54:03 INFO - Running post-action listener: _resource_record_post_action 04:54:03 INFO - Running post-run listener: _resource_record_post_run 04:54:04 INFO - Total resource usage - Wall time: 1271s; CPU: 14.0%; Read bytes: 10330112; Write bytes: 377110528; Read time: 7624; Write time: 4828796 04:54:04 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 6406144; Read time: 0; Write time: 3740 04:54:04 INFO - run-tests - Wall time: 1257s; CPU: 14.0%; Read bytes: 8568832; Write bytes: 370704384; Read time: 7548; Write time: 4825056 04:54:04 INFO - Running post-run listener: _upload_blobber_files 04:54:04 INFO - Blob upload gear active. 04:54:04 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:54:04 INFO - Copying logs to upload dir... 04:54:04 INFO - mkdir: /builds/slave/test/build/upload/logs 04:54:04 WARNING - returning nonzero exit status 1 program finished with exit code 1 elapsedTime=1405.421725 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' warnings (results: 1, elapsed: 23 mins, 25 secs) (at 2015-12-15 04:54:04.539641) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 04:54:04.543380) ========= 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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009875 build_url: 'https://queue.taskcluster.net/v1/task/A2hU46GWRaWV1eprmCXE8Q/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 04:54:04.593634) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:54:04.594016) ========= 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=256b35f542ca8bede9e27f1f00000291-1450179688.449411-31609210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:54:04.642660) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 04:54:04.642957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 04:54:04.643254) ========= ========= Total master_lag: 0.27 =========