builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-064 starttime: 1449163320.14 results: success (0) buildid: 20151203080144 builduid: f4b6602647b5467591f41bc8ae86f70f revision: 33d954cc69ff8e0bd22410e3641533e4fac500cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.141681) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.142192) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.142494) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005065 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.159677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.159996) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.173348) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.173656) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.223088) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.223397) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.223761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.224059) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-03 09:22:00-- 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% 226M=0s 2015-12-03 09:22:00 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099347 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.336971) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.337509) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022226 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.388016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.388371) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 33d954cc69ff8e0bd22410e3641533e4fac500cf --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 33d954cc69ff8e0bd22410e3641533e4fac500cf --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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-03 09:22:00,594 truncating revision to first 12 chars 2015-12-03 09:22:00,594 Setting DEBUG logging. 2015-12-03 09:22:00,594 attempt 1/10 2015-12-03 09:22:00,594 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/33d954cc69ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-03 09:22:00,870 unpacking tar archive at: mozilla-central-33d954cc69ff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.594817 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.996300) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:00.996717) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:01.004419) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:01.004737) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-03 09:22:01.005291) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-12-03 09:22:01.005653) ========= /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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:22:01 INFO - MultiFileLogger online at 20151203 09:22:01 in /builds/slave/test 09:22:01 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 09:22:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:22:01 INFO - {'append_to_log': False, 09:22:01 INFO - 'base_work_dir': '/builds/slave/test', 09:22:01 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:22:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:22:01 INFO - 'branch': 'Firefox-Non-PGO', 09:22:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:22:01 INFO - 'config_files': ('talos/linux_config.py',), 09:22:01 INFO - 'default_actions': ('clobber', 09:22:01 INFO - 'read-buildbot-config', 09:22:01 INFO - 'download-and-extract', 09:22:01 INFO - 'populate-webroot', 09:22:01 INFO - 'create-virtualenv', 09:22:01 INFO - 'install', 09:22:01 INFO - 'run-tests'), 09:22:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:22:01 INFO - 'download_minidump_stackwalk': True, 09:22:01 INFO - 'download_symbols': 'ondemand', 09:22:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:22:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:22:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:22:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:22:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:22:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:22:01 INFO - 'installer_path': 'installer.exe', 09:22:01 INFO - 'log_level': 'info', 09:22:01 INFO - 'log_name': 'talos', 09:22:01 INFO - 'log_to_console': True, 09:22:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:22:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:22:01 INFO - 'opt_config_files': (), 09:22:01 INFO - 'pip_index': False, 09:22:01 INFO - 'sps_profile': False, 09:22:01 INFO - 'sps_profile_interval': 0, 09:22:01 INFO - 'suite': 'dromaeojs-e10s', 09:22:01 INFO - 'system_bits': '64', 09:22:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:22:01 INFO - 'title': 'talos-linux64-ix-064', 09:22:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:22:01 INFO - 'use_talos_json': True, 09:22:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:22:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:22:01 INFO - 'work_dir': 'build'} 09:22:01 INFO - ##### 09:22:01 INFO - ##### Running clobber step. 09:22:01 INFO - ##### 09:22:01 INFO - Running pre-action listener: _resource_record_pre_action 09:22:01 INFO - Running main action method: clobber 09:22:01 INFO - rmtree: /builds/slave/test/build 09:22:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:22:02 INFO - Running post-action listener: _resource_record_post_action 09:22:02 INFO - ##### 09:22:02 INFO - ##### Running read-buildbot-config step. 09:22:02 INFO - ##### 09:22:02 INFO - Running pre-action listener: _resource_record_pre_action 09:22:02 INFO - Running main action method: read_buildbot_config 09:22:02 INFO - Using buildbot properties: 09:22:02 INFO - { 09:22:02 INFO - "properties": { 09:22:02 INFO - "buildnumber": 161, 09:22:02 INFO - "product": "firefox", 09:22:02 INFO - "script_repo_revision": "production", 09:22:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:22:02 INFO - "repository": "", 09:22:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:22:02 INFO - "buildid": "20151203080144", 09:22:02 INFO - "slavename": "talos-linux64-ix-064", 09:22:02 INFO - "pgo_build": "False", 09:22:02 INFO - "basedir": "/builds/slave/test", 09:22:02 INFO - "project": "", 09:22:02 INFO - "platform": "ubuntu64_hw", 09:22:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:22:02 INFO - "slavebuilddir": "test", 09:22:02 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:22:02 INFO - "branch": "mozilla-central", 09:22:02 INFO - "repo_path": "mozilla-central", 09:22:02 INFO - "stage_platform": "linux64", 09:22:02 INFO - "builduid": "f4b6602647b5467591f41bc8ae86f70f", 09:22:02 INFO - "revision": "33d954cc69ff8e0bd22410e3641533e4fac500cf" 09:22:02 INFO - }, 09:22:02 INFO - "sourcestamp": { 09:22:02 INFO - "repository": "", 09:22:02 INFO - "hasPatch": false, 09:22:02 INFO - "project": "", 09:22:02 INFO - "branch": "mozilla-central-linux64-talos", 09:22:02 INFO - "changes": [ 09:22:02 INFO - { 09:22:02 INFO - "category": null, 09:22:02 INFO - "files": [ 09:22:02 INFO - { 09:22:02 INFO - "url": null, 09:22:02 INFO - "name": "https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 09:22:02 INFO - } 09:22:02 INFO - ], 09:22:02 INFO - "repository": "", 09:22:02 INFO - "rev": "33d954cc69ff8e0bd22410e3641533e4fac500cf", 09:22:02 INFO - "who": "cbook@mozilla.com", 09:22:02 INFO - "when": 1449162870, 09:22:02 INFO - "number": 6739720, 09:22:02 INFO - "comments": "bug 1227340 - fix script to branch b2g-ota. a=sheriffduty a=NPOTB", 09:22:02 INFO - "project": "", 09:22:02 INFO - "at": "Thu 03 Dec 2015 09:14:30", 09:22:02 INFO - "branch": "mozilla-central-linux64-talos", 09:22:02 INFO - "revlink": "", 09:22:02 INFO - "properties": [ 09:22:02 INFO - [ 09:22:02 INFO - "buildid", 09:22:02 INFO - "20151203080144", 09:22:02 INFO - "Change" 09:22:02 INFO - ], 09:22:02 INFO - [ 09:22:02 INFO - "builduid", 09:22:02 INFO - "f4b6602647b5467591f41bc8ae86f70f", 09:22:02 INFO - "Change" 09:22:02 INFO - ], 09:22:02 INFO - [ 09:22:02 INFO - "pgo_build", 09:22:02 INFO - "False", 09:22:02 INFO - "Change" 09:22:02 INFO - ] 09:22:02 INFO - ], 09:22:02 INFO - "revision": "33d954cc69ff8e0bd22410e3641533e4fac500cf" 09:22:02 INFO - } 09:22:02 INFO - ], 09:22:02 INFO - "revision": "33d954cc69ff8e0bd22410e3641533e4fac500cf" 09:22:02 INFO - } 09:22:02 INFO - } 09:22:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 09:22:02 INFO - Running post-action listener: _resource_record_post_action 09:22:02 INFO - ##### 09:22:02 INFO - ##### Running download-and-extract step. 09:22:02 INFO - ##### 09:22:02 INFO - Running pre-action listener: _resource_record_pre_action 09:22:02 INFO - Running main action method: download_and_extract 09:22:02 INFO - mkdir: /builds/slave/test/build/tests 09:22:02 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')]} 09:22:02 INFO - https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:22:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/test_packages.json 09:22:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/test_packages.json 09:22:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:22:02 INFO - Downloaded 1302 bytes. 09:22:02 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:22:02 INFO - Using the following test package requirements: 09:22:02 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:22:02 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:22:02 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'jsshell-linux-x86_64.zip'], 09:22:02 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:22:02 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:22:02 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:22:02 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:22:02 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:22:02 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:22:02 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:22:02 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:22:02 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:22:02 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')]} 09:22:02 INFO - https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:22:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:22:03 INFO - Downloaded 21793022 bytes. 09:22:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 09:22:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:03 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:22:04 INFO - Return code: 0 09:22:04 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:22:05 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')]} 09:22:05 INFO - https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:22:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:22:06 INFO - Downloaded 21793022 bytes. 09:22:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 09:22:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:22:06 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:22:07 INFO - Return code: 0 09:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:07 INFO - https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:22:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:22:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:22:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:22:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:22:08 INFO - Downloaded 11132001 bytes. 09:22:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 09:22:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:22:08 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:22:08 INFO - Return code: 0 09:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:08 INFO - https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:22:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:22:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:22:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:22:09 INFO - Downloaded 57676968 bytes. 09:22:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:22:09 INFO - mkdir: /builds/slave/test/properties 09:22:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:22:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:22:09 INFO - Contents: 09:22:09 INFO - build_url:https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:22:09 INFO - Running post-action listener: _resource_record_post_action 09:22:09 INFO - Running post-action listener: set_extra_try_arguments 09:22:09 INFO - ##### 09:22:09 INFO - ##### Running populate-webroot step. 09:22:09 INFO - ##### 09:22:09 INFO - Running pre-action listener: _resource_record_pre_action 09:22:09 INFO - Running main action method: populate_webroot 09:22:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:22:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:22:09 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:22:09 INFO - '../fennec_ids.txt'], 09:22:09 INFO - 'tests': ['tcheck2']}, 09:22:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:22:09 INFO - '--tppagecycles', 09:22:09 INFO - '7'], 09:22:09 INFO - 'tests': ['tsvgm']}}, 09:22:09 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:22:09 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:22:09 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:22:09 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:22:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'tests': ['damp', 'tps']}, 09:22:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['damp', 'tps']}, 09:22:09 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:22:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:22:09 INFO - 'other': {'tests': ['a11yr', 09:22:09 INFO - 'ts_paint', 09:22:09 INFO - 'tpaint', 09:22:09 INFO - 'sessionrestore', 09:22:09 INFO - 'sessionrestore_no_auto_restore']}, 09:22:09 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['a11yr', 09:22:09 INFO - 'ts_paint', 09:22:09 INFO - 'tpaint', 09:22:09 INFO - 'sessionrestore', 09:22:09 INFO - 'sessionrestore_no_auto_restore']}, 09:22:09 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['a11yr', 09:22:09 INFO - 'ts_paint', 09:22:09 INFO - 'tpaint', 09:22:09 INFO - 'sessionrestore', 09:22:09 INFO - 'sessionrestore_no_auto_restore']}, 09:22:09 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['a11yr', 09:22:09 INFO - 'ts_paint', 09:22:09 INFO - 'tpaint', 09:22:09 INFO - 'sessionrestore', 09:22:09 INFO - 'sessionrestore_no_auto_restore']}, 09:22:09 INFO - 'other_l64': {'tests': ['a11yr', 09:22:09 INFO - 'ts_paint', 09:22:09 INFO - 'tpaint', 09:22:09 INFO - 'sessionrestore', 09:22:09 INFO - 'sessionrestore_no_auto_restore']}, 09:22:09 INFO - 'other_nol64': {'tests': ['a11yr', 09:22:09 INFO - 'ts_paint', 09:22:09 INFO - 'tpaint', 09:22:09 INFO - 'sessionrestore', 09:22:09 INFO - 'sessionrestore_no_auto_restore']}, 09:22:09 INFO - 'svgr': {'tests': ['tsvgx', 09:22:09 INFO - 'tsvgr_opacity', 09:22:09 INFO - 'tart', 09:22:09 INFO - 'tscrollx', 09:22:09 INFO - 'cart']}, 09:22:09 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['tsvgx', 09:22:09 INFO - 'tsvgr_opacity', 09:22:09 INFO - 'tart', 09:22:09 INFO - 'tscrollx', 09:22:09 INFO - 'cart']}, 09:22:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'tests': ['tp5o']}, 09:22:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'talos_options': ['--e10s'], 09:22:09 INFO - 'tests': ['tp5o']}, 09:22:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'talos_options': ['--xperf_path', 09:22:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:09 INFO - 'tests': ['tp5n']}, 09:22:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:09 INFO - 'talos_options': ['--e10s', 09:22:09 INFO - '--xperf_path', 09:22:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:09 INFO - 'tests': ['tp5n']}}, 09:22:09 INFO - 'talos.zip': {'path': '', 09:22:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:22:09 INFO - Running post-action listener: _resource_record_post_action 09:22:09 INFO - ##### 09:22:09 INFO - ##### Running create-virtualenv step. 09:22:09 INFO - ##### 09:22:09 INFO - Running pre-action listener: _resource_record_pre_action 09:22:09 INFO - Running main action method: create_virtualenv 09:22:09 INFO - Creating virtualenv /builds/slave/test/build/venv 09:22:09 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 09:22:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:22:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:22:10 INFO - Using real prefix '/usr' 09:22:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:22:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:22:12 INFO - Installing pip.................done. 09:22:12 INFO - Return code: 0 09:22:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:22:12 INFO - Using env: {'DISPLAY': ':0', 09:22:12 INFO - 'HOME': '/home/cltbld', 09:22:12 INFO - 'LANG': 'en_US.UTF-8', 09:22:12 INFO - 'LANGUAGE': 'en_US:en', 09:22:12 INFO - 'LOGNAME': 'cltbld', 09:22:12 INFO - 'MAIL': '/var/mail/cltbld', 09:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:12 INFO - 'MOZ_NO_REMOTE': '1', 09:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:12 INFO - 'NO_EM_RESTART': '1', 09:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:12 INFO - 'PWD': '/builds/slave/test', 09:22:12 INFO - 'SHELL': '/bin/bash', 09:22:12 INFO - 'SHLVL': '1', 09:22:12 INFO - 'TERM': 'linux', 09:22:12 INFO - 'TMOUT': '86400', 09:22:12 INFO - 'USER': 'cltbld', 09:22:12 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:12 INFO - '_': '/tools/buildbot/bin/python'} 09:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:12 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 09:22:12 INFO - Cleaning up... 09:22:12 INFO - Return code: 0 09:22:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:22:12 INFO - Using env: {'DISPLAY': ':0', 09:22:12 INFO - 'HOME': '/home/cltbld', 09:22:12 INFO - 'LANG': 'en_US.UTF-8', 09:22:12 INFO - 'LANGUAGE': 'en_US:en', 09:22:12 INFO - 'LOGNAME': 'cltbld', 09:22:12 INFO - 'MAIL': '/var/mail/cltbld', 09:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:12 INFO - 'MOZ_NO_REMOTE': '1', 09:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:12 INFO - 'NO_EM_RESTART': '1', 09:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:12 INFO - 'PWD': '/builds/slave/test', 09:22:12 INFO - 'SHELL': '/bin/bash', 09:22:12 INFO - 'SHLVL': '1', 09:22:12 INFO - 'TERM': 'linux', 09:22:12 INFO - 'TMOUT': '86400', 09:22:12 INFO - 'USER': 'cltbld', 09:22:12 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:12 INFO - '_': '/tools/buildbot/bin/python'} 09:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:12 INFO - Downloading/unpacking psutil>=0.7.1 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:14 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 09:22:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:22:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:14 INFO - Installing collected packages: psutil 09:22:14 INFO - Running setup.py install for psutil 09:22:14 INFO - building 'psutil._psutil_linux' extension 09:22:14 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 09:22:15 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 09:22:15 INFO - building 'psutil._psutil_posix' extension 09:22:15 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 09:22:15 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 09:22:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:15 INFO - Successfully installed psutil 09:22:15 INFO - Cleaning up... 09:22:15 INFO - Return code: 0 09:22:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:22:15 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')]} 09:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:15 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')]} 09:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:15 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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:15 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 09:22:15 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 09:22:15 INFO - Using env: {'DISPLAY': ':0', 09:22:15 INFO - 'HOME': '/home/cltbld', 09:22:15 INFO - 'LANG': 'en_US.UTF-8', 09:22:15 INFO - 'LANGUAGE': 'en_US:en', 09:22:15 INFO - 'LOGNAME': 'cltbld', 09:22:15 INFO - 'MAIL': '/var/mail/cltbld', 09:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:15 INFO - 'MOZ_NO_REMOTE': '1', 09:22:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:15 INFO - 'NO_EM_RESTART': '1', 09:22:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:15 INFO - 'PWD': '/builds/slave/test', 09:22:15 INFO - 'SHELL': '/bin/bash', 09:22:15 INFO - 'SHLVL': '1', 09:22:15 INFO - 'TERM': 'linux', 09:22:15 INFO - 'TMOUT': '86400', 09:22:15 INFO - 'USER': 'cltbld', 09:22:15 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:15 INFO - '_': '/tools/buildbot/bin/python'} 09:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:22:15 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 09:22:15 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 09:22:15 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 09:22:15 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 09:22:15 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 09:22:15 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 09:22:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:22:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:22:17 INFO - Installing collected packages: mozsystemmonitor 09:22:17 INFO - Running setup.py install for mozsystemmonitor 09:22:17 INFO - Successfully installed mozsystemmonitor 09:22:17 INFO - Cleaning up... 09:22:17 INFO - Return code: 0 09:22:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:17 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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:17 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 09:22:17 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 09:22:17 INFO - Using env: {'DISPLAY': ':0', 09:22:17 INFO - 'HOME': '/home/cltbld', 09:22:17 INFO - 'LANG': 'en_US.UTF-8', 09:22:17 INFO - 'LANGUAGE': 'en_US:en', 09:22:17 INFO - 'LOGNAME': 'cltbld', 09:22:17 INFO - 'MAIL': '/var/mail/cltbld', 09:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:17 INFO - 'MOZ_NO_REMOTE': '1', 09:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:17 INFO - 'NO_EM_RESTART': '1', 09:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:17 INFO - 'PWD': '/builds/slave/test', 09:22:17 INFO - 'SHELL': '/bin/bash', 09:22:17 INFO - 'SHLVL': '1', 09:22:17 INFO - 'TERM': 'linux', 09:22:17 INFO - 'TMOUT': '86400', 09:22:17 INFO - 'USER': 'cltbld', 09:22:17 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:17 INFO - '_': '/tools/buildbot/bin/python'} 09:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:22:17 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 09:22:17 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 09:22:17 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 09:22:17 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 09:22:17 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 09:22:17 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 09:22:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:22:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:22:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:22:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 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 09:22:19 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 09:22:20 INFO - Downloading docopt-0.6.1.tar.gz 09:22:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:22:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:22:20 INFO - Running setup.py install for blobuploader 09:22:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:22:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:22:20 INFO - Running setup.py install for requests 09:22:20 INFO - Running setup.py install for docopt 09:22:20 INFO - Successfully installed blobuploader requests docopt 09:22:20 INFO - Cleaning up... 09:22:20 INFO - Return code: 0 09:22:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:20 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')]} 09:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:20 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')]} 09:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:20 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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:20 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 09:22:20 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 09:22:20 INFO - Using env: {'DISPLAY': ':0', 09:22:20 INFO - 'HOME': '/home/cltbld', 09:22:20 INFO - 'LANG': 'en_US.UTF-8', 09:22:20 INFO - 'LANGUAGE': 'en_US:en', 09:22:20 INFO - 'LOGNAME': 'cltbld', 09:22:20 INFO - 'MAIL': '/var/mail/cltbld', 09:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:20 INFO - 'MOZ_NO_REMOTE': '1', 09:22:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:20 INFO - 'NO_EM_RESTART': '1', 09:22:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:20 INFO - 'PWD': '/builds/slave/test', 09:22:20 INFO - 'SHELL': '/bin/bash', 09:22:20 INFO - 'SHLVL': '1', 09:22:20 INFO - 'TERM': 'linux', 09:22:20 INFO - 'TMOUT': '86400', 09:22:20 INFO - 'USER': 'cltbld', 09:22:20 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:20 INFO - '_': '/tools/buildbot/bin/python'} 09:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:20 INFO - Running setup.py (path:/tmp/pip-Gd1b8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:20 INFO - Running setup.py (path:/tmp/pip-xi59D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:21 INFO - Running setup.py (path:/tmp/pip-YBc7DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:21 INFO - Running setup.py (path:/tmp/pip-MHQZVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:21 INFO - Running setup.py (path:/tmp/pip-v3SUag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:21 INFO - Running setup.py (path:/tmp/pip-JxGDrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:21 INFO - Running setup.py (path:/tmp/pip-wfAV5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:21 INFO - Running setup.py (path:/tmp/pip-ne70Xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:21 INFO - Running setup.py (path:/tmp/pip-TsE_xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:21 INFO - Running setup.py (path:/tmp/pip-hPgSJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:21 INFO - Running setup.py (path:/tmp/pip-XYUix5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:21 INFO - Running setup.py (path:/tmp/pip-pVXfUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:21 INFO - Running setup.py (path:/tmp/pip-7fgiRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:21 INFO - Running setup.py (path:/tmp/pip-b6gSyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:21 INFO - Running setup.py (path:/tmp/pip-VGyFt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:21 INFO - Running setup.py (path:/tmp/pip-bGNGQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:22 INFO - Running setup.py (path:/tmp/pip-A0ob36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:22:22 INFO - Running setup.py install for manifestparser 09:22:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Running setup.py install for mozcrash 09:22:22 INFO - Running setup.py install for mozdebug 09:22:22 INFO - Running setup.py install for mozdevice 09:22:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Running setup.py install for mozfile 09:22:22 INFO - Running setup.py install for mozhttpd 09:22:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Running setup.py install for mozinfo 09:22:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Running setup.py install for mozInstall 09:22:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:22:22 INFO - Running setup.py install for mozleak 09:22:23 INFO - Running setup.py install for mozlog 09:22:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Running setup.py install for moznetwork 09:22:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Running setup.py install for mozprocess 09:22:23 INFO - Running setup.py install for mozprofile 09:22:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Running setup.py install for mozrunner 09:22:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Running setup.py install for mozscreenshot 09:22:23 INFO - Running setup.py install for moztest 09:22:23 INFO - Running setup.py install for mozversion 09:22:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:22:23 INFO - Cleaning up... 09:22:23 INFO - Return code: 0 09:22:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:23 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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:23 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 09:22:23 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 09:22:23 INFO - Using env: {'DISPLAY': ':0', 09:22:23 INFO - 'HOME': '/home/cltbld', 09:22:23 INFO - 'LANG': 'en_US.UTF-8', 09:22:23 INFO - 'LANGUAGE': 'en_US:en', 09:22:23 INFO - 'LOGNAME': 'cltbld', 09:22:23 INFO - 'MAIL': '/var/mail/cltbld', 09:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:23 INFO - 'MOZ_NO_REMOTE': '1', 09:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:23 INFO - 'NO_EM_RESTART': '1', 09:22:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:23 INFO - 'PWD': '/builds/slave/test', 09:22:23 INFO - 'SHELL': '/bin/bash', 09:22:23 INFO - 'SHLVL': '1', 09:22:23 INFO - 'TERM': 'linux', 09:22:23 INFO - 'TMOUT': '86400', 09:22:23 INFO - 'USER': 'cltbld', 09:22:23 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:23 INFO - '_': '/tools/buildbot/bin/python'} 09:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:24 INFO - Running setup.py (path:/tmp/pip-Es2ENq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:24 INFO - Running setup.py (path:/tmp/pip-CwNrVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:24 INFO - Running setup.py (path:/tmp/pip-ruh8_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:24 INFO - Running setup.py (path:/tmp/pip-WXZlVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:24 INFO - Running setup.py (path:/tmp/pip-avYFTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:24 INFO - Running setup.py (path:/tmp/pip-YIpcDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:24 INFO - Running setup.py (path:/tmp/pip-o76mQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:24 INFO - Running setup.py (path:/tmp/pip-rFMb5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:24 INFO - Running setup.py (path:/tmp/pip-sTXB3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:24 INFO - Running setup.py (path:/tmp/pip-aONkyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:24 INFO - Running setup.py (path:/tmp/pip-JakLsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:24 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)) 09:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:24 INFO - Running setup.py (path:/tmp/pip-J5EVz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:25 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)) 09:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:25 INFO - Running setup.py (path:/tmp/pip-bazmuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:25 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)) 09:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:25 INFO - Running setup.py (path:/tmp/pip-AqLaWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:25 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)) 09:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:25 INFO - Running setup.py (path:/tmp/pip-JxSQb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:25 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)) 09:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:25 INFO - Running setup.py (path:/tmp/pip-RJiSB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:25 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)) 09:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:25 INFO - Running setup.py (path:/tmp/pip-4bZ1lP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:25 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)) 09:22:25 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)) 09:22:25 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)) 09:22:25 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)) 09:22:25 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)) 09:22:25 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)) 09:22:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:22:25 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 09:22:25 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 09:22:25 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 09:22:25 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 09:22:25 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 09:22:25 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 09:22:27 INFO - Downloading blessings-1.5.1.tar.gz 09:22:27 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 09:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:22:27 INFO - Installing collected packages: blessings 09:22:27 INFO - Running setup.py install for blessings 09:22:27 INFO - Successfully installed blessings 09:22:27 INFO - Cleaning up... 09:22:27 INFO - Return code: 0 09:22:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:22:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:27 INFO - Reading from file tmpfile_stdout 09:22:27 INFO - Current package versions: 09:22:27 INFO - argparse == 1.2.1 09:22:27 INFO - blessings == 1.5.1 09:22:27 INFO - blobuploader == 1.2.4 09:22:27 INFO - docopt == 0.6.1 09:22:27 INFO - manifestparser == 1.1 09:22:27 INFO - mozInstall == 1.12 09:22:27 INFO - mozcrash == 0.16 09:22:27 INFO - mozdebug == 0.1 09:22:27 INFO - mozdevice == 0.47 09:22:27 INFO - mozfile == 1.2 09:22:27 INFO - mozhttpd == 0.7 09:22:27 INFO - mozinfo == 0.9 09:22:27 INFO - mozleak == 0.1 09:22:27 INFO - mozlog == 3.1 09:22:27 INFO - moznetwork == 0.27 09:22:27 INFO - mozprocess == 0.22 09:22:27 INFO - mozprofile == 0.27 09:22:27 INFO - mozrunner == 6.11 09:22:27 INFO - mozscreenshot == 0.1 09:22:27 INFO - mozsystemmonitor == 0.0 09:22:27 INFO - moztest == 0.7 09:22:27 INFO - mozversion == 1.4 09:22:27 INFO - psutil == 3.1.1 09:22:27 INFO - requests == 1.2.3 09:22:27 INFO - wsgiref == 0.1.2 09:22:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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', '-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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:27 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 09:22:27 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 09:22:27 INFO - Using env: {'DISPLAY': ':0', 09:22:27 INFO - 'HOME': '/home/cltbld', 09:22:27 INFO - 'LANG': 'en_US.UTF-8', 09:22:27 INFO - 'LANGUAGE': 'en_US:en', 09:22:27 INFO - 'LOGNAME': 'cltbld', 09:22:27 INFO - 'MAIL': '/var/mail/cltbld', 09:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:27 INFO - 'MOZ_NO_REMOTE': '1', 09:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:27 INFO - 'NO_EM_RESTART': '1', 09:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:27 INFO - 'PWD': '/builds/slave/test', 09:22:27 INFO - 'SHELL': '/bin/bash', 09:22:27 INFO - 'SHLVL': '1', 09:22:27 INFO - 'TERM': 'linux', 09:22:27 INFO - 'TMOUT': '86400', 09:22:27 INFO - 'USER': 'cltbld', 09:22:27 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:27 INFO - '_': '/tools/buildbot/bin/python'} 09:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 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)) 09:22:27 INFO - Cleaning up... 09:22:27 INFO - Return code: 0 09:22:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22: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', 'jsonschema'] in /builds/slave/test/build 09:22: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 jsonschema 09:22:27 INFO - Using env: {'DISPLAY': ':0', 09:22:27 INFO - 'HOME': '/home/cltbld', 09:22:27 INFO - 'LANG': 'en_US.UTF-8', 09:22:27 INFO - 'LANGUAGE': 'en_US:en', 09:22:27 INFO - 'LOGNAME': 'cltbld', 09:22:27 INFO - 'MAIL': '/var/mail/cltbld', 09:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:27 INFO - 'MOZ_NO_REMOTE': '1', 09:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:27 INFO - 'NO_EM_RESTART': '1', 09:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:27 INFO - 'PWD': '/builds/slave/test', 09:22:27 INFO - 'SHELL': '/bin/bash', 09:22:27 INFO - 'SHLVL': '1', 09:22:27 INFO - 'TERM': 'linux', 09:22:27 INFO - 'TMOUT': '86400', 09:22:27 INFO - 'USER': 'cltbld', 09:22:27 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:27 INFO - '_': '/tools/buildbot/bin/python'} 09:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:27 INFO - Downloading/unpacking jsonschema 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:22:29 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 09:22:29 INFO - Downloading/unpacking functools32 (from jsonschema) 09:22:29 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 09:22:29 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 09:22:29 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 09:22:29 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 09:22:29 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 09:22:29 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 09:22:29 INFO - Downloading functools32-3.2.3-2.tar.gz 09:22:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 09:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:22:29 INFO - warning: no files found matching '*.txt' 09:22:29 INFO - no previously-included directories found matching 'build' 09:22:29 INFO - no previously-included directories found matching 'dist' 09:22:29 INFO - no previously-included directories found matching '.git*' 09:22:29 INFO - Installing collected packages: jsonschema, functools32 09:22:29 INFO - Running setup.py install for functools32 09:22:29 INFO - warning: no files found matching '*.txt' 09:22:29 INFO - no previously-included directories found matching 'build' 09:22:29 INFO - no previously-included directories found matching 'dist' 09:22:29 INFO - no previously-included directories found matching '.git*' 09:22:30 INFO - Successfully installed jsonschema functools32 09:22:30 INFO - Cleaning up... 09:22:30 INFO - Return code: 0 09:22:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:22:30 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')]} 09:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:30 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')]} 09:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:30 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 0x7ffc3734df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1219240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', '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 09:22:30 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 09:22:30 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 09:22:30 INFO - Using env: {'DISPLAY': ':0', 09:22:30 INFO - 'HOME': '/home/cltbld', 09:22:30 INFO - 'LANG': 'en_US.UTF-8', 09:22:30 INFO - 'LANGUAGE': 'en_US:en', 09:22:30 INFO - 'LOGNAME': 'cltbld', 09:22:30 INFO - 'MAIL': '/var/mail/cltbld', 09:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:30 INFO - 'MOZ_NO_REMOTE': '1', 09:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:30 INFO - 'NO_EM_RESTART': '1', 09:22:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:30 INFO - 'PWD': '/builds/slave/test', 09:22:30 INFO - 'SHELL': '/bin/bash', 09:22:30 INFO - 'SHLVL': '1', 09:22:30 INFO - 'TERM': 'linux', 09:22:30 INFO - 'TMOUT': '86400', 09:22:30 INFO - 'USER': 'cltbld', 09:22:30 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:30 INFO - '_': '/tools/buildbot/bin/python'} 09:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:30 INFO - Downloading/unpacking flake8 09:22:30 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 09:22:30 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 09:22:30 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 09:22:30 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 09:22:30 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 09:22:30 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 09:22:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:22:31 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 09:22:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:22:32 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 09:22:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 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 09:22:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 09:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:22:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:22:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:22:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:22:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:22:32 INFO - no previously-included directories found matching 'docs/_build' 09:22:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:22:32 INFO - Running setup.py install for pep8 09:22:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:22:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:22:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:22:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:22:32 INFO - no previously-included directories found matching 'docs/_build' 09:22:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:22:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:22:32 INFO - Cleaning up... 09:22:32 INFO - Return code: 0 09:22:32 INFO - Running post-action listener: _resource_record_post_action 09:22:32 INFO - Running post-action listener: _start_resource_monitoring 09:22:32 INFO - Starting resource monitoring. 09:22:32 INFO - ##### 09:22:32 INFO - ##### Running install step. 09:22:32 INFO - ##### 09:22:32 INFO - Running pre-action listener: _resource_record_pre_action 09:22:32 INFO - Running main action method: install 09:22:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:33 INFO - Reading from file tmpfile_stdout 09:22:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:22:33 INFO - Reading from file tmpfile_stdout 09:22:33 INFO - Output received: 09:22:33 INFO - Usage: mozinstall [options] installer 09:22:33 INFO - Options: 09:22:33 INFO - -h, --help show this help message and exit 09:22:33 INFO - -d DEST, --destination=DEST 09:22:33 INFO - Directory to install application into. [default: 09:22:33 INFO - "/builds/slave/test"] 09:22:33 INFO - --app=APP Application being installed. [default: firefox] 09:22:33 INFO - mkdir: /builds/slave/test/build/application 09:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:22:48 INFO - Reading from file tmpfile_stdout 09:22:48 INFO - Output received: 09:22:48 INFO - /builds/slave/test/build/application/firefox/firefox 09:22:48 INFO - Running post-action listener: _resource_record_post_action 09:22:48 INFO - ##### 09:22:48 INFO - ##### Running run-tests step. 09:22:48 INFO - ##### 09:22:48 INFO - Running pre-action listener: _resource_record_pre_action 09:22:48 INFO - Running main action method: run_tests 09:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:22:48 INFO - Python 2.7.3 09:22:48 INFO - Return code: 0 09:22:48 INFO - grabbing minidump binary from tooltool 09:22: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')]} 09:22:48 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 0x12a80b0>, '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 0x12a8fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a9440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:22:48 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 09:22:48 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 09:22:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:48 INFO - Return code: 0 09:22:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:22:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:22:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:22:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:22:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:22:49 INFO - Return code: 0 09:22:49 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/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost'] in /builds/slave/test/build 09:22:49 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/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost 09:22:49 INFO - Using env: {'DISPLAY': ':0', 09:22:49 INFO - 'HOME': '/home/cltbld', 09:22:49 INFO - 'LANG': 'en_US.UTF-8', 09:22:49 INFO - 'LANGUAGE': 'en_US:en', 09:22:49 INFO - 'LOGNAME': 'cltbld', 09:22:49 INFO - 'MAIL': '/var/mail/cltbld', 09:22:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:22:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:49 INFO - 'MOZ_NO_REMOTE': '1', 09:22:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:49 INFO - 'NO_EM_RESTART': '1', 09:22:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:49 INFO - 'PWD': '/builds/slave/test', 09:22:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:22:49 INFO - 'SHELL': '/bin/bash', 09:22:49 INFO - 'SHLVL': '1', 09:22:49 INFO - 'TERM': 'linux', 09:22:49 INFO - 'TMOUT': '86400', 09:22:49 INFO - 'USER': 'cltbld', 09:22:49 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605', 09:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:49 INFO - '_': '/tools/buildbot/bin/python'} 09:22:49 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/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost'] with output_timeout 3600 09:22:49 INFO - mozversion INFO | application_buildid: 20151203080144 09:22:49 INFO - mozversion INFO | application_changeset: 33d954cc69ff8e0bd22410e3641533e4fac500cf 09:22:49 INFO - mozversion INFO | application_display_name: Nightly 09:22:49 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:49 INFO - mozversion INFO | application_name: Firefox 09:22:49 INFO - mozversion INFO | application_remotingname: firefox 09:22:49 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:22:49 INFO - mozversion INFO | application_vendor: Mozilla 09:22:49 INFO - mozversion INFO | application_version: 45.0a1 09:22:49 INFO - mozversion INFO | platform_buildid: 20151203080144 09:22:49 INFO - mozversion INFO | platform_changeset: 33d954cc69ff8e0bd22410e3641533e4fac500cf 09:22:49 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:22:49 INFO - mozversion INFO | platform_version: 45.0a1 09:22:49 INFO - 2015-12-03 09:22:49,232 DEBUG : using testdate: 1449163369 09:22:49 INFO - 2015-12-03 09:22:49,233 DEBUG : actual date: 1449163369 09:22:49 INFO - 2015-12-03 09:22:49,243 INFO : starting webserver on 'localhost:39160' 09:22:49 INFO - 2015-12-03 09:22:49,244 INFO : Starting test suite talos-linux64-ix-064.e 09:22:49 INFO - 2015-12-03 09:22:49,244 INFO : Starting test dromaeo_css 09:22:49 INFO - 2015-12-03 09:22:49,244 DEBUG : operating with platform_type : linux_ 09:22:49 INFO - 2015-12-03 09:22:49,245 INFO : Initialising browser for dromaeo_css test... 09:22:49 INFO - 2015-12-03 09:22:49,257 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJMnPqp/profile http://localhost:39160/getInfo.html 09:22:53 INFO - 2015-12-03 09:22:53,836 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:22:53 INFO - 2015-12-03 09:22:53,837 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:22:53 INFO - 2015-12-03 09:22:53,837 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:22:53 INFO - 2015-12-03 09:22:53,837 DEBUG : BROWSER_OUTPUT: __metrics 09:22:53 INFO - 2015-12-03 09:22:53,958 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] 09:22:54 INFO - 2015-12-03 09:22:54,847 INFO : Browser initialized. 09:22:54 INFO - 2015-12-03 09:22:54,847 INFO : Running cycle 1/1 for dromaeo_css test... 09:22:54 INFO - 2015-12-03 09:22:54,848 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJMnPqp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:23:06 INFO - 2015-12-03 09:23:06,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 143040512 09:23:06 INFO - 2015-12-03 09:23:06,848 DEBUG : BROWSER_OUTPUT: 09:23:56 INFO - 2015-12-03 09:23:56,499 DEBUG : BROWSER_OUTPUT: 1449163436498 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] 09:26:08 INFO - 2015-12-03 09:26:08,230 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39160/tests/dromaeo/cssquery-ext.html) 09:26:09 INFO - 2015-12-03 09:26:09,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 145838080 09:26:09 INFO - 2015-12-03 09:26:09,347 DEBUG : BROWSER_OUTPUT: 09:29:11 INFO - 2015-12-03 09:29:11,406 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/dromaeo/cssquery-ext.html (next: http://localhost:39160/tests/dromaeo/cssquery-jquery.html) 09:29:12 INFO - 2015-12-03 09:29:12,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 144150528 09:29:12 INFO - 2015-12-03 09:29:12,008 DEBUG : BROWSER_OUTPUT: 09:32:13 INFO - 2015-12-03 09:32:13,844 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39160/tests/dromaeo/cssquery-mootools.html) 09:32:14 INFO - 2015-12-03 09:32:14,438 DEBUG : BROWSER_OUTPUT: RSS: Main: 145272832 09:32:14 INFO - 2015-12-03 09:32:14,438 DEBUG : BROWSER_OUTPUT: 09:35:16 INFO - 2015-12-03 09:35:16,230 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39160/tests/dromaeo/cssquery-prototype.html) 09:35:16 INFO - 2015-12-03 09:35:16,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 144297984 09:35:16 INFO - 2015-12-03 09:35:16,845 DEBUG : BROWSER_OUTPUT: 09:38:19 INFO - 2015-12-03 09:38:19,377 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39160/tests/dromaeo/cssquery-yui.html) 09:38:20 INFO - 2015-12-03 09:38:20,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 145334272 09:38:20 INFO - 2015-12-03 09:38:20,003 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,510 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/dromaeo/cssquery-yui.html (next: http://localhost:39160/tests/dromaeo/cssquery-dojo.html) 09:41:22 INFO - 2015-12-03 09:41:22,755 DEBUG : BROWSER_OUTPUT: RSS: Main: 146948096 09:41:22 INFO - 2015-12-03 09:41:22,755 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,757 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:41:22 INFO - 2015-12-03 09:41:22,757 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:41:22 INFO - 2015-12-03 09:41:22,757 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:41:22 INFO - 2015-12-03 09:41:22,757 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:41:22 INFO - 2015-12-03 09:41:22,759 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2651.8939242430297;2710.2817753295367;2737.014674717102;2737.7946654000957;2745.29994851313;4538.047010127877;4721.78751956162;4739.194336962721;4783.665143439961;4795.568398844103;4654.8370807021765;4661;4681.531846815316;4726.243800991842;4743.169945259583;4258.297380932158;4323.481182258133;4432.601065904068;4437.822487100512;4463.107378524308;4196.958030419687;4313.051128751669;4357.847475338348;4404.075144219718;4429.224885645015;4229.830806767725;4236.788160591958;4343.782810859476;4352.695311328208;4372.737635741865;4329.415528903589;4381.07997320563;4401.867943961686;4418.845340413102;4449.510553839107;4123.814428350702;4241.278982572939;4364.476262848447;4396.780160991938;4423.911521769579;4624.722516648978;4704.717716936994;4732.384789977281;4734.502877197913;4755.667103302771;5034.496550344973;5040.16837221858;5048.899022019576;5050.217216331474;5066.468020857794;4896.265560165968;4950.331705219785;5001.44984051754;5044.596432285409;5086.21163719624;4677;4742.122707299091;4752.786124624366;4773.0453909218295;4803.711777293373;5190.506901844319;5200.739963001834;5202.505761952609;5207.3490813648295;5217.869553261199;4695.647826412965;4778.116048531033;4794.664373493822;4800.73595952226;5056.292119103328;4929.876753081202;5000.349954505891;5011.398632164163;5042.193249080129;5072.340595722532;4958.950410495849;4978.178600530872;4983.775730092155;4990.72551009698;5042.84871453857;4763.523647635278;4876.902461950741;4961.8511444656715;4987.675801072991;4999.375078115236;4124.1133156371525;4148.751074935513;4177.540470548237;4201.243776120266;4214.557471465513;3661.9450708239397;3686.299603075407;3696.168362118502;3717.0893131182493;3727.9440808387894;4146.896327591834;4211.684123690736;4227.112306415625;4313.094250207429;4359.80380882861;4944.703317800943;4968.1554135797005;5011.323471331397;5022.573081288131;5064.240363945438;4297.677674174387;4315.136972605492;4327.978360108243;4362.629176519968;4377.606015458624;4342.218400687843;4432.091421258634;4436.3123715824095;4441.289393697058;4452.799624016927;4696.38946936896;4722.763861806827;4723.31511930775;4750.192467280502;4791.161546729239;5093.745312734274;5101.056304583664;5115.411727651219;5119.948800512097;5131.255967884561;4596.149712303235;4691.366665500113;4731.266653668687;4827.806887724451;4890.119778439773;4974.5771609413605;5002.699838009731;5003.424606170337;5008;5032.421271553818;5028.069807085701;5074.416442109205;5075.746212689277;5083.92374114396;5120.18077107661;4664.277037059262;4780.713157210578;4806.519348065165;4892.535209155125;4925.088858561035;4830.1305764961235;4834;4843.830465933675;4854.660173787801;4887.022595480989;4737.786799593958;4742.715437073786;4744.146053710365;4752.2158843790385;4755.453122890912;4762.285657151455;4863.9513604864915;4884.633652476007;4891;4951.579115775199;4789.521047895322;4794.640401969936;4814.085323788469;4824.782884770124;4833.589144922721 09:41:22 INFO - 2015-12-03 09:41:22,760 DEBUG : BROWSER_OUTPUT: |1;ext.html;557.5177471487164;566.5297802823657;570.6604570280684;572.2846441947565;580.1964279472934;33176.66823331766;33607.83196084022;33810.49284260735;33811.83094084526;33824.83087584565;38487.61512384875;39025.80487097568;39100.21799564007;39121.804390978075;39160.60839391605;7786.182450842651;7880.960595197016;7941;7942.4043196760185;7943.086545047307;7781.961090194541;7817.374610031212;7867.134615192324;7895.171007044264;7896.881546776803;7770.33952114071;7839;7839.6472158752995;7852.725154619561;7862.842743145134;7849.843003139934;7893.723719669784;7904.565248911307;7920.48516846406;7936.960315198445;7681.3854891608535;7749.4187935904465;7810.375169986388;7811.4922530035365;7860.606969651494;36611.4508282376;37143.814280928695;37169.44245836315;37179;37191.44212836809;2706.093458691341;2762.875670594828;2762.9723702763117;2772.7227277272314;2896.826190428581;25437.364065898317;29953.700462995308;30301.393972120655;30512.237194070327;30682.386352273053;3437.1063523483826;3454.101933497284;3484.4424892017155;3515.1915059536195;3567.233044895334;206048;206649;208456;208857;208967;46576;47100;47241.5275847244;47708.284375734394;55151.172732409046;56535.71732141313;56589.43410565924;56619.15071273934;56620.43379566234;56919.14621280684;13043.934780326037;13044.804327935088;13065.412056457482;13090.869091309156;13132.540361087507;10659.840102398315;10767.138628909668;10782.35305881634;10863.565457381615;10972;19002.90498547526;19202.711959320623;19289.421317360502;19301.324453644054;19330.226790928486;7641.235876412426;7808.882866757003;7863.29230369258;8003.639836207268;8021.598920054091;771.3944553525456;795.0300633227454;913.6619450803246;960.9855852162223;1033.26121822897;25365.112221072173;25403.364915876882;25696.743032569437;25702.100426484983;25895;967.8306296398102;972.0376826941277;997.3317877022413;1012.6405126180247;1097.8078836203633;13345.9115729788;13465.528706495421;13531.526396576073;13686.52097176594;13810.447582096804;309193;311943;313221;314460;314762;46328.305075423894;46545;46552.7672361636;46575;46881.765591171825;1221.7190046289224;1249.9812502812465;1251.4118364368733;1260.691130672991;1261.6845788552862;24908.12821551237;25358.239252822445;25528.872355638105;25533.489330213662;25678.486430271663;38284.234315312984;38462.230755385295;38842.22315553729;38885.416718748675;38895.6110438892;235517;236866;237087;237625;239828;29428.55857162187;41833.581664182966;42443.7877810609;42846.14307713766;43088.78455607702;38725.6127438722;38820;39053.2189356217;39903;40071.599284006785;33569.83215083909;34382;34436.48345274773;34436.61237202565;35175.296494070484;34120.82939585286;34121.146971324924;34162.31675366528;34550.65449345474;34581.82709086438;33591.66408335885;33982.320353593284;34111.829440852634;34294.65705343014;34462.65537344695;30675.539866901567;31113.688863111078;31698.366032679678;31842.522362164123;31904.202394940312;24482.877585611957;30936;31973.680263198;31976.68023319737;32099.67900320967 09:41:22 INFO - 2015-12-03 09:41:22,760 DEBUG : BROWSER_OUTPUT: |2;jquery.html;324.5227388531881;325.9266665000375;336.1797214795898;339.80800847521164;340.6678488473738;14921.850781492181;15285.388584456623;15816;16457;16752.413665521693;10078.647247346344;10224.897751022487;13974.510892118778;15562.37750489979;15615;4519.8418055368065;4576.633869290449;4648.047150334173;4682.016776476927;4682.367880336145;4618.191816432128;4662.487126416091;4662.860114196579;4666.416697912761;4668.019715859674;4640.373549570815;4647.581717645411;4920.6801557898625;5100.030994111125;5100.69395836251;5005.224190250517;5045.823396181116;5058.418281897573;5062.873428164288;5108.182690769478;5080.415752188509;5086.109930762102;5108.616853735947;5122.718250496221;5131.84604461863;10966.122710183166;11329.943350283278;13186.340682965812;17336.826631733646;17436.302547898067;1790.409164975568;1863.1429542410508;1929.1415320182525;1938.709193620954;1944.1445763863856;8868.645254189825;8870.955645221797;8881.955590222073;8971.416857904216;9081.954590227073;1739.2434291083418;1767.920443580029;1768.32803534656;1807.7378780076815;1814.637072585488;52485;55732.721336393464;56399.43600563983;56608;56725.71637141788;9580.712578622653;9628.807423851624;9884.851727224097;9892.159166470787;9927.50362481873;12437.689057773447;12477.376131193403;12511.624651260478;12525.311107889154;12546.435410406553;6736.326367363225;6967.33810288032;7013.89479157813;7074.080369551925;7119.00333953247;6678.131842860397;6726.058351830748;6741.157355330583;6775.864482710318;6790.5925644461495;4093.283675356801;4142.150859073882;4148.191102734938;4149.439825623502;4178.26880295947;1697.3041053168142;1716.957076073108;1726.5597272695634;1738.6001219719535;1773.8314860088271;725.575538310084;776.720163061759;823.3742355809628;853.6756032707532;885.1679421343916;15013.774793378108;15025.098494090389;15717.842821571867;15829.129397883227;15832.445864394691;830.2071521696765;843.5360551696665;853.4282031039219;857.9356548258905;872.3544577013091;8351.457155284887;8653.783655408664;8913.955430222808;8979.551022448852;8993.325500587482;448663;451633;456377;457967;463498;67964.6601766988;69745.30254697388;71886.64056679684;72048.63975680088;73004;1063.4895250279753;1068.6259809066764;1082.8971247731454;1104.4422566603973;1113.8774734779167;13527;13978.580642580739;14511.172975691648;14611.415543378145;14756;32461.513077303385;32926.67073329332;33192.33615327728;33955.15112122217;34172.48741268933;41097.794511027256;43065;43212.783936081374;44196.779016104716;45512.54487455216;8241.546714930737;8686.04453510126;8834.602442889956;9012.098790120936;9048;10077.445740484343;10235.539400726837;10297.74255643615;10372.896271037192;12928.741425171633;12030.819537707117;12979.28613926243;12994.350282486026;13066.738665226832;13507.797383039459;12724.74550508966;12945.676358091123;12976;13463.12489688167;13788;12754.68460018757;13194.208347499181;13274;13319;13363.331833408485;8757.211850933447;8955.910440895768;8989.955050224708;9067.138621830916;9228.907710923073;12457.314847683465;13486.57183302148;13491.797623035858;13667.248301343521;13777.173369597856 09:41:22 INFO - 2015-12-03 09:41:22,761 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1484.4878516911674;1627.5361521966238;1697.312588401697;1698.3800912666877;1733.2893513659394;5879.88240235195;6231.189945307104;6717;7000.824979375512;7056.047433596463;6842.486813488996;7039.190493093296;7043.2956704329545;7043.577385356883;7082.96458517708;1983.4743792894894;2008.2569449303778;2008.7489063867017;2010.4270282969358;2019.5960807838417;2065.9690104648444;2098.4229336932312;2100.2649072824465;2103.3584756649216;2105.473631592102;2074.1184996376555;2095.8428117891217;2096.968545471819;2097.842661800371;2106.9578608427823;2070.81362677359;2086.248950377863;2098.2551194326024;2099.4226587688354;2103.915843366271;2070.068469188871;2093.8743675379374;2117.269542008005;2120.74551053873;2140.1760322276036;7106.822329441754;7192.928070719278;7197.820054498627;7209.0628218331285;7213.170485394195;4203.327467605199;4275.636570891478;4280.721753086071;4294.677899157576;4304.849330273457;2245.2141750387395;2300.183434880627;2765.834049957009;2809.384603852772;2811.4517669054544;842.1283971089954;847.8473874702551;868.1145231863527;889.6930558957149;898.137787723786;9510.09654082868;9596.616135354578;9658.71023869285;9666.806663866753;9681.564329605184;3968.543617483969;3974.8608798691907;4082.3876418537457;4103.5691252418665;4110.17796440713;7887.487313324616;7961.164077771867;8070.192980702001;8076.71731489395;8088.636011379502;4267.65858731307;4276.444062271885;4293.205756934977;4302.82788688449;4312.417823593837;3921.3725803871243;3925.7644541327613;3941;3948.44721738951;3959.9604003960167;3138.814103684762;3173.5715678383585;3201.983990080035;3211.9197020074216;3222.5649537312634;892.0410558649478;901.8557030875029;902.0483390023458;902.5126431726925;903.9457632542069;762.0778857582387;769.6344236487736;775.0583041604477;775.4571799740091;887.6183241206214;6459.09572659828;6504.284528701839;6511.38141876521;6513.381228783258;6526.2494813096155;420.3274760383362;428.1821720513866;429.4910531020688;430.07319227065096;434.96737744669275;5029.874253143701;5050.974745126251;5073.061483625467;5109.6678715883345;5126.359205099363;7382.704691812375;7439.516431432048;8284.295834854105;8328.208820162075;8553.401261911606;8117.107118216992;8142.3486121111355;8145.063317718538;8186.549739764251;8187.754367368989;6728.865422691617;6745.494087943423;6846.4522838173825;6888.035675005503;6893.517453778188;6720.8319792005595;6729;6731.32686731323;6734.292899245509;6779.389854913082;7498.962505187439;7598.924010759822;7691.5769632670745;7792.220777922163;7870.645820937957;7847.489913155627;7910.841783164419;7936.84126317482;7956.482828616122;8047.557384343683;5913.763449461973;5968.582199246011;6012.188354572077;6027.789027384196;6036.6679832609625;6892.069570607904;6907.551009184387;6943.444524438134;6958.165020197609;6965;6434.613923164625;6532.542722009413;6571.507136964651;6597.736090556516;6683.832904177434;6419.165508483868;6456.9354306456335;6515.543911926309;6577.704003319766;6625.304343043911;6374.681265936777;6400.327965563549;6405.423511883952;6406.903896441464;6418.807435776934;5656.547476201812;5658;5707.086866101404;5710.200571920018;5711.571632127524;6053.031514957585;6151.753929842756;6192.566520343533;6196.349383314687;6210.534209934182 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: |4;prototype.html;279.55550674427656;287.7151619896302;287.97552208062314;294.2599362603054;297.95083811171156;5487.451254874511;5487.780488780454;5539.1968164616155;5559.249501317321;5595.916061259074;5465.535429488488;5475.288212532365;5496.532794712454;5498.285222921015;5509.944900550993;4254.127903779718;4254.936175957348;4256;4262.1688770689725;4266.488021437432;4256.7445953242905;4260.595243451882;4264.97867510662;4265.0403659176745;4277.187334406468;3998.3202855514487;4164.375343698439;4216.97891510542;4225.260579398609;4226.281532139528;4154.4183814266025;4155.688323375759;4157.293260145782;4159.500859896817;4170.291050521403;3587.4798154267487;3589.4436362363876;3593.4070878305047;3594.4788005739283;3599.0642432967616;5284.445133261029;5299.072662284123;5304.124819404794;5311.840644780624;5314.388845282765;4619;4700.1539722849875;4778.378810754614;4782;4801.135795556798;5634.211210430543;5752.913706294409;5760.711964401805;5776.451237132465;5791.507721843648;3908.9445849620442;3927.6857851372106;3933.7443066200603;3934.9606503935165;3936.5079365079364;5966.403359664042;6061.454469097803;6067.666278354689;6074.271087469532;6088.573799834014;3943.6056394360335;3996.620321069494;4017.5580686124504;4020.9396859047138;4071.063655359284;4096.365063415175;4101.507819061672;4107.815148318333;4109.116539943926;4114.1977314424;4782.760861956888;4785.976070119627;4787.8563643090765;4790;4790.089882922233;5781.508571771446;5803.303603567514;5804.448577385142;5816.447437493432;5830.41695830422;1351.9797203041962;1422.9644258893404;1428.2787192467738;1429.2639290765264;1430.6852492451644;871.4727589808201;950.9429434233969;952.9904700953041;960.9471479068578;977.8435450327913;4976.7262800545595;4984.501549845059;4993.825216117419;4994.650374473825;5004.199328107486;5967.403259673998;5972.731227094791;5984.102384642339;5985.40145985398;5989.431004054608;4980.477049909779;4986.177280748709;4990.476000020019;4995.125852975715;5002.52476014778;3096.210466331071;3224.6452890182063;3239.6760323967414;3240.4977228529615;3244.043007312848;5564.360098588714;5604.943950560524;5613.691246981373;5631.310606817939;5640.492355688008;6119.112728654409;6136.8772624548155;6140.232470941132;6147.231596050494;6155.84610384735;5454.481824226692;5583.553315734732;5609.5792815539;5618.297712785902;5626.915596266142;5946.73239704223;5965.075413310944;5966.731497082555;5973.402659733992;5982.760689572542;5837.211976383134;5841.707914604338;5847.7368518417625;5848.736806843617;5859.619124757048;5464.480874316933;5572.86067848307;5590.468905453975;5590.860228494157;5599.748011339418;4023.8994025149605;4032;4034.91930161401;4047.9797601011805;4056.0671045659074;5961.076033214858;5966.075258334965;5966.612170208922;5967.1944287522365;5976.820695379146;5713.8285851424525;5721.22763426932;5721.284839395075;5723.542116630742;5730.713464326684;5591.161325800999;5728.513076388387;5741.024025915688;5742.856428589319;5816.94183058164;5643.266375371176;5688.260526131577;5689.630174038674;5701.7149142541875;5701.942980570308;5704.971475142598;5837.562182836219;5859.853503662272;5868.972929737364;5893.263342082239;5687.573431992534;5698.857528561819;5707.486326230659;5737.34020587621;5741.311042674739 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: |5;yui.html;571.6912867051792;642.8585711143547;669.8091044052444;724.5000949344951;731.7914394397598;11268.380239086848;11684.182107252496;11699.532018719252;11707.5902343418;11715.062794976402;11161.553537858475;11943.283402995827;11975;11980;12053;616.8519555306716;620.8789286089215;769.0963118335961;769.246138783992;782.1513657681422;790.4032455496092;794.308951212446;798.1738900238255;799.952002879829;800.1438460846896;823.0411570520337;826.8511667899776;828.6561077152975;829.9087100418949;830.7424698343533;824.3570015388007;824.7979245084932;825.1418524734299;825.7233826668073;825.9876101858476;605.3583201806056;610.5817515002237;610.7495926670044;611.6513587255266;616.017452163798;12156.088293378032;12242.387880606024;12306.138570300083;12320.383980800923;12403;10420.374777513374;10490.31812932157;10537.104346130589;10685.41230232337;10854.403007834566;8779.20987111163;8861.867071993925;8876.8668469973;8927.687530936386;8951.865722014176;3188.689851410044;3200.983995080033;3230.353929214143;3237.27161388688;3240.335731175127;40799.388009179886;40834.38748418776;40843.59156408428;40898.386524202455;41041;9159.129882661136;9205.723828285161;9449.527523623792;9562.28282878773;9641.469719165372;16359.672806543802;16697.833021669627;16714.916425417796;16734.665306693798;16735.49793506197;6277.937220627735;6330.936690633035;6335.68321583919;6335.968320158462;6489.902651460232;3474;3568.1436455250555;3600.3159399713973;3654.6528079832374;3662.8901132966053;2603;2647.3381654586356;2654.853983030952;2663.414048909237;2668.2662267876103;453.66882176011694;459.2582978489715;459.47160765119713;477.40562999066384;478.4067755982626;463.0114705104626;467.67496589869717;485.085613618332;506.67572753437827;570.1590154522047;7411.851762964819;7518.473706840578;7578.54528728267;7606.087269527581;7623.313901748869;517.5161224255334;563.6682098076296;589.3340525206489;596.1326270276737;613.6318209074609;3551.236484155919;3669.9266014679556;3734.421164719473;3771.9434208486896;3830.3296923038356;49726;50473.24290135651;50533.24200137001;50732;51067.23399149016;45811.77094114575;46053.30920036202;46390.30414543785;46547.76726116348;46737;41553.37669934954;41877.79061104675;41989.79005104955;42028.57971420247;42128.78935605364;6505.544611877123;6611.66941652925;6674.866502670016;6736.7642132525125;6794.286599906938;31457.685423145474;31593.52609710902;32355.514667279538;32439.351212975136;33054.5041824368;38656.80671596624;39436.802815986885;41071.589284106776;42464.575354247296;42952;9598.472084035166;9743.610255589698;9760.56077476501;9802.435400065617;9960.900390995997;11456.3698996556;11514.53941842317;11527.250728702607;11647.59233426826;11761.059115270587;9284;9960.50197490108;10215.94892025535;10666.146708263475;10667.306625069346;10101.14140298083;10491.422971736627;10497.685069447929;10603.94698026505;10941.781164376509;9923.702288931343;9939.204863611038;10047.949760251153;10282.948585257025;10336.534855931352;8914.37599368064;9189.264858811146;9262.814743704954;9310.767230819283;9320.161185493338;8671.956640216758;8797.921954078394;9184.219341355792;9293.349465537347;9342.439453632804 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,343 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: __startTimestamp1449164482756__endTimestamp 09:41:22 INFO - 2015-12-03 09:41:22,763 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,764 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:41:22 INFO - 2015-12-03 09:41:22,764 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:41:22 INFO - 2015-12-03 09:41:22,764 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,765 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4635.35 Median:4752.79 stddev:478.82 (10.1%) stddev-sans-first:454.30 09:41:22 INFO - 2015-12-03 09:41:22,765 DEBUG : BROWSER_OUTPUT: Values: 2651.9 2710.3 2737.0 2737.8 2745.3 4538.0 4721.8 4739.2 4783.7 4795.6 4654.8 4661.0 4681.5 4726.2 4743.2 4258.3 4323.5 4432.6 4437.8 4463.1 4197.0 4313.1 4357.8 4404.1 4429.2 4229.8 4236.8 4343.8 4352.7 4372.7 4329.4 4381.1 4401.9 4418.8 4449.5 4123.8 4241.3 4364.5 4396.8 4423.9 4624.7 4704.7 4732.4 4734.5 4755.7 5034.5 5040.2 5048.9 5050.2 5066.5 4896.3 4950.3 5001.4 5044.6 5086.2 4677.0 4742.1 4752.8 4773.0 4803.7 5190.5 5200.7 5202.5 5207.3 5217.9 4695.6 4778.1 4794.7 4800.7 5056.3 4929.9 5000.3 5011.4 5042.2 5072.3 4959.0 4978.2 4983.8 4990.7 5042.8 4763.5 4876.9 4961.9 4987.7 4999.4 4124.1 4148.8 4177.5 4201.2 4214.6 3661.9 3686.3 3696.2 3717.1 3727.9 4146.9 4211.7 4227.1 4313.1 4359.8 4944.7 4968.2 5011.3 5022.6 5064.2 4297.7 4315.1 4328.0 4362.6 4377.6 4342.2 4432.1 4436.3 4441.3 4452.8 4696.4 4722.8 4723.3 4750.2 4791.2 5093.7 5101.1 5115.4 5119.9 5131.3 4596.1 4691.4 4731.3 4827.8 4890.1 4974.6 5002.7 5003.4 5008.0 5032.4 5028.1 5074.4 5075.7 5083.9 5120.2 4664.3 4780.7 4806.5 4892.5 4925.1 4830.1 4834.0 4843.8 4854.7 4887.0 4737.8 4742.7 4744.1 4752.2 4755.5 4762.3 4864.0 4884.6 4891.0 4951.6 4789.5 4794.6 4814.1 4824.8 4833.6 09:41:22 INFO - 2015-12-03 09:41:22,765 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,765 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:41561.71 Median:34449.63 stddev:66944.74 (194.3%) stddev-sans-first:67061.74 09:41:22 INFO - 2015-12-03 09:41:22,766 DEBUG : BROWSER_OUTPUT: Values: 557.5 566.5 570.7 572.3 580.2 33176.7 33607.8 33810.5 33811.8 33824.8 38487.6 39025.8 39100.2 39121.8 39160.6 7786.2 7881.0 7941.0 7942.4 7943.1 7782.0 7817.4 7867.1 7895.2 7896.9 7770.3 7839.0 7839.6 7852.7 7862.8 7849.8 7893.7 7904.6 7920.5 7937.0 7681.4 7749.4 7810.4 7811.5 7860.6 36611.5 37143.8 37169.4 37179.0 37191.4 2706.1 2762.9 2763.0 2772.7 2896.8 25437.4 29953.7 30301.4 30512.2 30682.4 3437.1 3454.1 3484.4 3515.2 3567.2 206048.0 206649.0 208456.0 208857.0 208967.0 46576.0 47100.0 47241.5 47708.3 55151.2 56535.7 56589.4 56619.2 56620.4 56919.1 13043.9 13044.8 13065.4 13090.9 13132.5 10659.8 10767.1 10782.4 10863.6 10972.0 19002.9 19202.7 19289.4 19301.3 19330.2 7641.2 7808.9 7863.3 8003.6 8021.6 771.4 795.0 913.7 961.0 1033.3 25365.1 25403.4 25696.7 25702.1 25895.0 967.8 972.0 997.3 1012.6 1097.8 13345.9 13465.5 13531.5 13686.5 13810.4 309193.0 311943.0 313221.0 314460.0 314762.0 46328.3 46545.0 46552.8 46575.0 46881.8 1221.7 1250.0 1251.4 1260.7 1261.7 24908.1 25358.2 25528.9 25533.5 25678.5 38284.2 38462.2 38842.2 38885.4 38895.6 235517.0 236866.0 237087.0 237625.0 239828.0 29428.6 41833.6 42443.8 42846.1 43088.8 38725.6 38820.0 39053.2 39903.0 40071.6 33569.8 34382.0 34436.5 34436.6 35175.3 34120.8 34121.1 34162.3 34550.7 34581.8 33591.7 33982.3 34111.8 34294.7 34462.7 30675.5 31113.7 31698.4 31842.5 31904.2 24482.9 30936.0 31973.7 31976.7 32099.7 09:41:22 INFO - 2015-12-03 09:41:22,766 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,766 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25097.95 Median:23621.63 stddev:74513.62 (315.4%) stddev-sans-first:74699.43 09:41:22 INFO - 2015-12-03 09:41:22,767 DEBUG : BROWSER_OUTPUT: Values: 324.5 325.9 336.2 339.8 340.7 14921.9 15285.4 15816.0 16457.0 16752.4 10078.6 10224.9 13974.5 15562.4 15615.0 4519.8 4576.6 4648.0 4682.0 4682.4 4618.2 4662.5 4662.9 4666.4 4668.0 4640.4 4647.6 4920.7 5100.0 5100.7 5005.2 5045.8 5058.4 5062.9 5108.2 5080.4 5086.1 5108.6 5122.7 5131.8 10966.1 11329.9 13186.3 17336.8 17436.3 1790.4 1863.1 1929.1 1938.7 1944.1 8868.6 8871.0 8882.0 8971.4 9082.0 1739.2 1767.9 1768.3 1807.7 1814.6 52485.0 55732.7 56399.4 56608.0 56725.7 9580.7 9628.8 9884.9 9892.2 9927.5 12437.7 12477.4 12511.6 12525.3 12546.4 6736.3 6967.3 7013.9 7074.1 7119.0 6678.1 6726.1 6741.2 6775.9 6790.6 4093.3 4142.2 4148.2 4149.4 4178.3 1697.3 1717.0 1726.6 1738.6 1773.8 725.6 776.7 823.4 853.7 885.2 15013.8 15025.1 15717.8 15829.1 15832.4 830.2 843.5 853.4 857.9 872.4 8351.5 8653.8 8914.0 8979.6 8993.3 448663.0 451633.0 456377.0 457967.0 463498.0 67964.7 69745.3 71886.6 72048.6 73004.0 1063.5 1068.6 1082.9 1104.4 1113.9 13527.0 13978.6 14511.2 14611.4 14756.0 32461.5 32926.7 33192.3 33955.2 34172.5 41097.8 43065.0 43212.8 44196.8 45512.5 8241.5 8686.0 8834.6 9012.1 9048.0 10077.4 10235.5 10297.7 10372.9 12928.7 12030.8 12979.3 12994.4 13066.7 13507.8 12724.7 12945.7 12976.0 13463.1 13788.0 12754.7 13194.2 13274.0 13319.0 13363.3 8757.2 8955.9 8990.0 9067.1 9228.9 12457.3 13486.6 13491.8 13667.2 13777.2 09:41:22 INFO - 2015-12-03 09:41:22,768 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,768 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4915.42 Median:6412.86 stddev:2574.72 (40.1%) stddev-sans-first:2569.03 09:41:22 INFO - 2015-12-03 09:41:22,769 DEBUG : BROWSER_OUTPUT: Values: 1484.5 1627.5 1697.3 1698.4 1733.3 5879.9 6231.2 6717.0 7000.8 7056.0 6842.5 7039.2 7043.3 7043.6 7083.0 1983.5 2008.3 2008.7 2010.4 2019.6 2066.0 2098.4 2100.3 2103.4 2105.5 2074.1 2095.8 2097.0 2097.8 2107.0 2070.8 2086.2 2098.3 2099.4 2103.9 2070.1 2093.9 2117.3 2120.7 2140.2 7106.8 7192.9 7197.8 7209.1 7213.2 4203.3 4275.6 4280.7 4294.7 4304.8 2245.2 2300.2 2765.8 2809.4 2811.5 842.1 847.8 868.1 889.7 898.1 9510.1 9596.6 9658.7 9666.8 9681.6 3968.5 3974.9 4082.4 4103.6 4110.2 7887.5 7961.2 8070.2 8076.7 8088.6 4267.7 4276.4 4293.2 4302.8 4312.4 3921.4 3925.8 3941.0 3948.4 3960.0 3138.8 3173.6 3202.0 3211.9 3222.6 892.0 901.9 902.0 902.5 903.9 762.1 769.6 775.1 775.5 887.6 6459.1 6504.3 6511.4 6513.4 6526.2 420.3 428.2 429.5 430.1 435.0 5029.9 5051.0 5073.1 5109.7 5126.4 7382.7 7439.5 8284.3 8328.2 8553.4 8117.1 8142.3 8145.1 8186.5 8187.8 6728.9 6745.5 6846.5 6888.0 6893.5 6720.8 6729.0 6731.3 6734.3 6779.4 7499.0 7598.9 7691.6 7792.2 7870.6 7847.5 7910.8 7936.8 7956.5 8047.6 5913.8 5968.6 6012.2 6027.8 6036.7 6892.1 6907.6 6943.4 6958.2 6965.0 6434.6 6532.5 6571.5 6597.7 6683.8 6419.2 6456.9 6515.5 6577.7 6625.3 6374.7 6400.3 6405.4 6406.9 6418.8 5656.5 5658.0 5707.1 5710.2 5711.6 6053.0 6151.8 6192.6 6196.3 6210.5 09:41:22 INFO - 2015-12-03 09:41:22,770 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,770 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4754.04 Median:5492.16 stddev:1423.58 (25.9%) stddev-sans-first:1387.39 09:41:22 INFO - 2015-12-03 09:41:22,770 DEBUG : BROWSER_OUTPUT: Values: 279.6 287.7 288.0 294.3 298.0 5487.5 5487.8 5539.2 5559.2 5595.9 5465.5 5475.3 5496.5 5498.3 5509.9 4254.1 4254.9 4256.0 4262.2 4266.5 4256.7 4260.6 4265.0 4265.0 4277.2 3998.3 4164.4 4217.0 4225.3 4226.3 4154.4 4155.7 4157.3 4159.5 4170.3 3587.5 3589.4 3593.4 3594.5 3599.1 5284.4 5299.1 5304.1 5311.8 5314.4 4619.0 4700.2 4778.4 4782.0 4801.1 5634.2 5752.9 5760.7 5776.5 5791.5 3908.9 3927.7 3933.7 3935.0 3936.5 5966.4 6061.5 6067.7 6074.3 6088.6 3943.6 3996.6 4017.6 4020.9 4071.1 4096.4 4101.5 4107.8 4109.1 4114.2 4782.8 4786.0 4787.9 4790.0 4790.1 5781.5 5803.3 5804.4 5816.4 5830.4 1352.0 1423.0 1428.3 1429.3 1430.7 871.5 950.9 953.0 960.9 977.8 4976.7 4984.5 4993.8 4994.7 5004.2 5967.4 5972.7 5984.1 5985.4 5989.4 4980.5 4986.2 4990.5 4995.1 5002.5 3096.2 3224.6 3239.7 3240.5 3244.0 5564.4 5604.9 5613.7 5631.3 5640.5 6119.1 6136.9 6140.2 6147.2 6155.8 5454.5 5583.6 5609.6 5618.3 5626.9 5946.7 5965.1 5966.7 5973.4 5982.8 5837.2 5841.7 5847.7 5848.7 5859.6 5464.5 5572.9 5590.5 5590.9 5599.7 4023.9 4032.0 4034.9 4048.0 4056.1 5961.1 5966.1 5966.6 5967.2 5976.8 5713.8 5721.2 5721.3 5723.5 5730.7 5591.2 5728.5 5741.0 5742.9 5816.9 5643.3 5688.3 5689.6 5701.7 5701.9 5705.0 5837.6 5859.9 5869.0 5893.3 5687.6 5698.9 5707.5 5737.3 5741.3 09:41:22 INFO - 2015-12-03 09:41:22,770 DEBUG : BROWSER_OUTPUT: 09:41:22 INFO - 2015-12-03 09:41:22,771 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12335.65 Median:23607.20 stddev:14225.21 (60.3%) stddev-sans-first:14237.68 09:41:22 INFO - 2015-12-03 09:41:22,772 DEBUG : BROWSER_OUTPUT: Values: 571.7 642.9 669.8 724.5 731.8 11268.4 11684.2 11699.5 11707.6 11715.1 11161.6 11943.3 11975.0 11980.0 12053.0 616.9 620.9 769.1 769.2 782.2 790.4 794.3 798.2 800.0 800.1 823.0 826.9 828.7 829.9 830.7 824.4 824.8 825.1 825.7 826.0 605.4 610.6 610.7 611.7 616.0 12156.1 12242.4 12306.1 12320.4 12403.0 10420.4 10490.3 10537.1 10685.4 10854.4 8779.2 8861.9 8876.9 8927.7 8951.9 3188.7 3201.0 3230.4 3237.3 3240.3 40799.4 40834.4 40843.6 40898.4 41041.0 9159.1 9205.7 9449.5 9562.3 9641.5 16359.7 16697.8 16714.9 16734.7 16735.5 6277.9 6330.9 6335.7 6336.0 6489.9 3474.0 3568.1 3600.3 3654.7 3662.9 2603.0 2647.3 2654.9 2663.4 2668.3 453.7 459.3 459.5 477.4 478.4 463.0 467.7 485.1 506.7 570.2 7411.9 7518.5 7578.5 7606.1 7623.3 517.5 563.7 589.3 596.1 613.6 3551.2 3669.9 3734.4 3771.9 3830.3 49726.0 50473.2 50533.2 50732.0 51067.2 45811.8 46053.3 46390.3 46547.8 46737.0 41553.4 41877.8 41989.8 42028.6 42128.8 6505.5 6611.7 6674.9 6736.8 6794.3 31457.7 31593.5 32355.5 32439.4 33054.5 38656.8 39436.8 41071.6 42464.6 42952.0 9598.5 9743.6 9760.6 9802.4 9960.9 11456.4 11514.5 11527.3 11647.6 11761.1 9284.0 9960.5 10215.9 10666.1 10667.3 10101.1 10491.4 10497.7 10603.9 10941.8 9923.7 9939.2 10047.9 10282.9 10336.5 8914.4 9189.3 9262.8 9310.8 9320.2 8672.0 8797.9 9184.2 9293.3 9342.4 09:41:22 INFO - 2015-12-03 09:41:22,772 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:41:22 INFO - 2015-12-03 09:41:22,772 DEBUG : BROWSER_OUTPUT: 09:41:23 INFO - 2015-12-03 09:41:23,491 INFO : Browser exited with error code: 0 09:41:23 INFO - 2015-12-03 09:41:23,510 INFO : Completed test dromaeo_css (00:18:34) 09:41:23 INFO - 2015-12-03 09:41:23,510 INFO : Starting test kraken 09:41:23 INFO - 2015-12-03 09:41:23,510 DEBUG : operating with platform_type : linux_ 09:41:23 INFO - 2015-12-03 09:41:23,510 INFO : Initialising browser for kraken test... 09:41:23 INFO - 2015-12-03 09:41:23,516 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQkvDq9/profile http://localhost:39160/getInfo.html 09:41:28 INFO - 2015-12-03 09:41:28,002 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:41:28 INFO - 2015-12-03 09:41:28,003 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:41:28 INFO - 2015-12-03 09:41:28,003 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:41:28 INFO - 2015-12-03 09:41:28,003 DEBUG : BROWSER_OUTPUT: __metrics 09:41:28 INFO - 2015-12-03 09:41:28,125 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] 09:41:29 INFO - 2015-12-03 09:41:29,015 INFO : Browser initialized. 09:41:29 INFO - 2015-12-03 09:41:29,015 INFO : Running cycle 1/1 for kraken test... 09:41:29 INFO - 2015-12-03 09:41:29,015 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQkvDq9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:41:41 INFO - 2015-12-03 09:41:41,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 143482880 09:41:41 INFO - 2015-12-03 09:41:41,168 DEBUG : BROWSER_OUTPUT: 09:41:43 INFO - 2015-12-03 09:41:43,770 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39160/tests/kraken/driver.html?testName=audio-beat-detection) 09:41:44 INFO - 2015-12-03 09:41:44,384 DEBUG : BROWSER_OUTPUT: RSS: Main: 142966784 09:41:44 INFO - 2015-12-03 09:41:44,384 DEBUG : BROWSER_OUTPUT: 09:41:47 INFO - 2015-12-03 09:41:47,142 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39160/tests/kraken/driver.html?testName=audio-dft) 09:41:47 INFO - 2015-12-03 09:41:47,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 141606912 09:41:47 INFO - 2015-12-03 09:41:47,736 DEBUG : BROWSER_OUTPUT: 09:41:50 INFO - 2015-12-03 09:41:50,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39160/tests/kraken/driver.html?testName=audio-fft) 09:41:51 INFO - 2015-12-03 09:41:51,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 138379264 09:41:51 INFO - 2015-12-03 09:41:51,220 DEBUG : BROWSER_OUTPUT: 09:41:53 INFO - 2015-12-03 09:41:53,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39160/tests/kraken/driver.html?testName=audio-oscillator) 09:41:53 INFO - 2015-12-03 09:41:53,905 DEBUG : BROWSER_OUTPUT: RSS: Main: 139354112 09:41:53 INFO - 2015-12-03 09:41:53,905 DEBUG : BROWSER_OUTPUT: 09:41:56 INFO - 2015-12-03 09:41:56,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39160/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:41:56 INFO - 2015-12-03 09:41:56,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 140697600 09:41:56 INFO - 2015-12-03 09:41:56,741 DEBUG : BROWSER_OUTPUT: 09:41:59 INFO - 2015-12-03 09:41:59,813 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39160/tests/kraken/driver.html?testName=imaging-darkroom) 09:42:00 INFO - 2015-12-03 09:42:00,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 140914688 09:42:00 INFO - 2015-12-03 09:42:00,415 DEBUG : BROWSER_OUTPUT: 09:42:03 INFO - 2015-12-03 09:42:03,885 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39160/tests/kraken/driver.html?testName=imaging-desaturate) 09:42:04 INFO - 2015-12-03 09:42:04,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 138817536 09:42:04 INFO - 2015-12-03 09:42:04,487 DEBUG : BROWSER_OUTPUT: 09:42:07 INFO - 2015-12-03 09:42:07,394 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39160/tests/kraken/driver.html?testName=json-parse-financial) 09:42:07 INFO - 2015-12-03 09:42:07,987 DEBUG : BROWSER_OUTPUT: RSS: Main: 139026432 09:42:07 INFO - 2015-12-03 09:42:07,987 DEBUG : BROWSER_OUTPUT: 09:42:09 INFO - 2015-12-03 09:42:09,749 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39160/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:42:10 INFO - 2015-12-03 09:42:10,343 DEBUG : BROWSER_OUTPUT: RSS: Main: 140955648 09:42:10 INFO - 2015-12-03 09:42:10,343 DEBUG : BROWSER_OUTPUT: 09:42:12 INFO - 2015-12-03 09:42:12,234 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:42:12 INFO - 2015-12-03 09:42:12,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 140021760 09:42:12 INFO - 2015-12-03 09:42:12,848 DEBUG : BROWSER_OUTPUT: 09:42:15 INFO - 2015-12-03 09:42:15,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:42:15 INFO - 2015-12-03 09:42:15,642 DEBUG : BROWSER_OUTPUT: RSS: Main: 140681216 09:42:15 INFO - 2015-12-03 09:42:15,643 DEBUG : BROWSER_OUTPUT: 09:42:18 INFO - 2015-12-03 09:42:18,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:42:18 INFO - 2015-12-03 09:42:18,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 141021184 09:42:18 INFO - 2015-12-03 09:42:18,865 DEBUG : BROWSER_OUTPUT: 09:42:21 INFO - 2015-12-03 09:42:21,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:42:22 INFO - 2015-12-03 09:42:22,102 DEBUG : BROWSER_OUTPUT: RSS: Main: 141119488 09:42:22 INFO - 2015-12-03 09:42:22,102 DEBUG : BROWSER_OUTPUT: 09:42:23 INFO - 2015-12-03 09:42:23,856 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39160/tests/kraken/driver.html?testName=ai-astar) 09:42:24 INFO - 2015-12-03 09:42:24,106 DEBUG : BROWSER_OUTPUT: RSS: Main: 139579392 09:42:24 INFO - 2015-12-03 09:42:24,106 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,110 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:42:24 INFO - 2015-12-03 09:42:24,110 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:42:24 INFO - 2015-12-03 09:42:24,110 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:42:24 INFO - 2015-12-03 09:42:24,110 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:42:24 INFO - 2015-12-03 09:42:24,110 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;107;114;114;113;126;113;114;113;120 09:42:24 INFO - 2015-12-03 09:42:24,111 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;163;142;159;171;132;153;153;139;166;131 09:42:24 INFO - 2015-12-03 09:42:24,111 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;162;161;161;164;172;165;164;166;164 09:42:24 INFO - 2015-12-03 09:42:24,111 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;71;84;136;74;77;75;73;92 09:42:24 INFO - 2015-12-03 09:42:24,111 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;132;95;95;95;138;97;95;133 09:42:24 INFO - 2015-12-03 09:42:24,111 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;118;111;111;111;110;110;110;110;111;111 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;149;147;148;149;150;148;148;147 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;97;96;96;97;96;96;97;96 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;72;71;72;72;70;71;71;73;75 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;69;69;69;69;69;68;70;68 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;84;84;84;85;94;83;84;98;98 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;128;126;119;125;129;139;125;131;124 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;149;149;154;159;144;149;148;151;145 09:42:24 INFO - 2015-12-03 09:42:24,112 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;70;72;72;70;70;69;69;68;69 09:42:24 INFO - 2015-12-03 09:42:24,113 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:42:24 INFO - 2015-12-03 09:42:24,113 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:42:24 INFO - 2015-12-03 09:42:24,113 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,788 09:42:24 INFO - 2015-12-03 09:42:24,113 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:42:24 INFO - 2015-12-03 09:42:24,113 DEBUG : BROWSER_OUTPUT: __startTimestamp1449164544108__endTimestamp 09:42:24 INFO - 2015-12-03 09:42:24,113 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.70 Median:114.00 stddev:5.03 (4.4%) stddev-sans-first:5.30 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: Values: 113.0 107.0 114.0 114.0 113.0 126.0 113.0 114.0 113.0 120.0 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.90 Median:156.00 stddev:14.25 (9.1%) stddev-sans-first:14.42 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: Values: 163.0 142.0 159.0 171.0 132.0 153.0 153.0 139.0 166.0 131.0 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:3.25 (2.0%) stddev-sans-first:3.35 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: Values: 162.0 162.0 161.0 161.0 164.0 172.0 165.0 164.0 166.0 164.0 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.60 Median:74.50 stddev:19.88 (26.7%) stddev-sans-first:20.71 09:42:24 INFO - 2015-12-03 09:42:24,117 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 71.0 84.0 136.0 74.0 77.0 75.0 73.0 92.0 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.70 Median:95.00 stddev:19.15 (20.2%) stddev-sans-first:19.76 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 132.0 95.0 95.0 95.0 138.0 97.0 95.0 133.0 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:111.30 Median:111.00 stddev:2.41 (2.2%) stddev-sans-first:0.53 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: Values: 118.0 111.0 111.0 111.0 110.0 110.0 110.0 110.0 111.0 111.0 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 149.0 147.0 148.0 149.0 150.0 148.0 148.0 147.0 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 97.0 96.0 96.0 97.0 96.0 96.0 97.0 96.0 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.00 Median:72.00 stddev:1.41 (2.0%) stddev-sans-first:1.45 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 71.0 72.0 72.0 70.0 71.0 71.0 73.0 75.0 09:42:24 INFO - 2015-12-03 09:42:24,118 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 69.0 69.0 69.0 69.0 69.0 68.0 70.0 68.0 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:85.00 stddev:6.17 (7.3%) stddev-sans-first:6.46 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: Values: 85.0 84.0 84.0 84.0 85.0 94.0 83.0 84.0 98.0 98.0 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:128.50 stddev:5.25 (4.1%) stddev-sans-first:5.55 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: Values: 129.0 128.0 126.0 119.0 125.0 129.0 139.0 125.0 131.0 124.0 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.30 Median:149.00 stddev:4.55 (3.1%) stddev-sans-first:4.55 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: Values: 145.0 149.0 149.0 154.0 159.0 144.0 149.0 148.0 151.0 145.0 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:1.62 (2.3%) stddev-sans-first:1.36 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: Values: 73.0 70.0 72.0 72.0 70.0 70.0 69.0 69.0 68.0 69.0 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:42:24 INFO - 2015-12-03 09:42:24,119 DEBUG : BROWSER_OUTPUT: 09:42:24 INFO - 2015-12-03 09:42:24,991 INFO : Browser exited with error code: 0 09:42:25 INFO - 2015-12-03 09:42:25,044 INFO : Completed test kraken (00:01:01) 09:42:25 INFO - 2015-12-03 09:42:25,044 INFO : Starting test v8_7 09:42:25 INFO - 2015-12-03 09:42:25,044 DEBUG : operating with platform_type : linux_ 09:42:25 INFO - 2015-12-03 09:42:25,044 INFO : Initialising browser for v8_7 test... 09:42:25 INFO - 2015-12-03 09:42:25,051 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa7eXxa/profile http://localhost:39160/getInfo.html 09:42:29 INFO - 2015-12-03 09:42:29,675 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:42:29 INFO - 2015-12-03 09:42:29,676 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:42:29 INFO - 2015-12-03 09:42:29,676 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:42:29 INFO - 2015-12-03 09:42:29,676 DEBUG : BROWSER_OUTPUT: __metrics 09:42:29 INFO - 2015-12-03 09:42:29,805 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] 09:42:30 INFO - 2015-12-03 09:42:30,680 INFO : Browser initialized. 09:42:30 INFO - 2015-12-03 09:42:30,680 INFO : Running cycle 1/1 for v8_7 test... 09:42:30 INFO - 2015-12-03 09:42:30,681 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa7eXxa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:42:42 INFO - 2015-12-03 09:42:42,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 143073280 09:42:42 INFO - 2015-12-03 09:42:42,710 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,182 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39160/tests/v8_7/run.html (next: http://localhost:39160/tests/v8_7/run.html) 09:43:04 INFO - 2015-12-03 09:43:04,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 138584064 09:43:04 INFO - 2015-12-03 09:43:04,432 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: |0;Richards;165.7550140891762 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.08236165093467 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 09:43:04 INFO - 2015-12-03 09:43:04,433 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1072.961373390558 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: |5;Earley;710.7320540156361 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: |6;Boyer;12268.292682926829 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: |7;RegExp;25846.153846153848 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: |8;Splay;588.9281507656066 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: __startTimestamp1449164584431__endTimestamp 09:43:04 INFO - 2015-12-03 09:43:04,434 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.76 Median:165.76 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: Values: 165.8 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.08 Median:185.08 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: Values: 185.1 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,435 DEBUG : BROWSER_OUTPUT: Values: 278.1 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: Values: 5202.1 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1072.96 Median:1072.96 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: Values: 1073.0 09:43:04 INFO - 2015-12-03 09:43:04,436 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,437 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:710.73 Median:710.73 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,437 DEBUG : BROWSER_OUTPUT: Values: 710.7 09:43:04 INFO - 2015-12-03 09:43:04,437 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,437 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: Values: 12268.3 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25846.15 Median:25846.15 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: Values: 25846.2 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:588.93 Median:588.93 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: Values: 588.9 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: Values: 4830.9 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:43:04 INFO - 2015-12-03 09:43:04,438 DEBUG : BROWSER_OUTPUT: 09:43:05 INFO - 2015-12-03 09:43:05,311 INFO : Browser exited with error code: 0 09:43:05 INFO - 2015-12-03 09:43:05,325 INFO : Completed test v8_7 (00:00:40) 09:43:05 INFO - 2015-12-03 09:43:05,395 INFO : Completed test suite (00:20:16) 09:43:05 INFO - 2015-12-03 09:43:05,395 DEBUG : Working with test: dromaeo_css 09:43:05 INFO - 2015-12-03 09:43:05,395 DEBUG : Generating results file: dromaeo_css 09:43:05 INFO - 2015-12-03 09:43:05,396 DEBUG : Working with test: kraken 09:43:05 INFO - 2015-12-03 09:43:05,396 DEBUG : Generating results file: kraken 09:43:05 INFO - 2015-12-03 09:43:05,397 INFO : javascript benchmark 09:43:05 INFO - 2015-12-03 09:43:05,397 DEBUG : Working with test: v8_7 09:43:05 INFO - 2015-12-03 09:43:05,397 DEBUG : Generating results file: v8_7 09:43:05 INFO - 2015-12-03 09:43:05,397 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:43:05 INFO - 2015-12-03 09:43:05,457 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 09:43:05 INFO - 2015-12-03 09:43:05,457 DEBUG : process_Request line: dromaeo_css 6250.63 graph.html#tests=[[72,94,43]] 09:43:05 INFO - 2015-12-03 09:43:05,457 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:43:05 INFO - 2015-12-03 09:43:05,487 DEBUG : process_Request line: kraken 1550.40 graph.html#tests=[[232,94,43]] 09:43:05 INFO - 2015-12-03 09:43:05,487 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:43:05 INFO - 2015-12-03 09:43:05,518 DEBUG : process_Request line: v8_7 21429.63 graph.html#tests=[[230,94,43]] 09:43:05 INFO - 2015-12-03 09:43:05,519 INFO : javascript benchmark 09:43:05 INFO - 2015-12-03 09:43:05,523 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2651.8939242430297, 2710.2817753295367, 2737.014674717102, 2737.7946654000957, 2745.29994851313, 4538.047010127877, 4721.78751956162, 4739.194336962721, 4783.665143439961, 4795.568398844103, 4654.8370807021765, 4661.0, 4681.531846815316, 4726.243800991842, 4743.169945259583, 4258.297380932158, 4323.481182258133, 4432.601065904068, 4437.822487100512, 4463.107378524308, 4196.958030419687, 4313.051128751669, 4357.847475338348, 4404.075144219718, 4429.224885645015, 4229.830806767725, 4236.788160591958, 4343.782810859476, 4352.695311328208, 4372.737635741865, 4329.415528903589, 4381.07997320563, 4401.867943961686, 4418.845340413102, 4449.510553839107, 4123.814428350702, 4241.278982572939, 4364.476262848447, 4396.780160991938, 4423.911521769579, 4624.722516648978, 4704.717716936994, 4732.384789977281, 4734.502877197913, 4755.667103302771, 5034.496550344973, 5040.16837221858, 5048.899022019576, 5050.217216331474, 5066.468020857794, 4896.265560165968, 4950.331705219785, 5001.44984051754, 5044.596432285409, 5086.21163719624, 4677.0, 4742.122707299091, 4752.786124624366, 4773.0453909218295, 4803.711777293373, 5190.506901844319, 5200.739963001834, 5202.505761952609, 5207.3490813648295, 5217.869553261199, 4695.647826412965, 4778.116048531033, 4794.664373493822, 4800.73595952226, 5056.292119103328, 4929.876753081202, 5000.349954505891, 5011.398632164163, 5042.193249080129, 5072.340595722532, 4958.950410495849, 4978.178600530872, 4983.775730092155, 4990.72551009698, 5042.84871453857, 4763.523647635278, 4876.902461950741, 4961.8511444656715, 4987.675801072991, 4999.375078115236, 4124.1133156371525, 4148.751074935513, 4177.540470548237, 4201.243776120266, 4214.557471465513, 3661.9450708239397, 3686.299603075407, 3696.168362118502, 3717.0893131182493, 3727.9440808387894, 4146.896327591834, 4211.684123690736, 4227.112306415625, 4313.094250207429, 4359.80380882861, 4944.703317800943, 4968.1554135797005, 5011.323471331397, 5022.573081288131, 5064.240363945438, 4297.677674174387, 4315.136972605492, 4327.978360108243, 4362.629176519968, 4377.606015458624, 4342.218400687843, 4432.091421258634, 4436.3123715824095, 4441.289393697058, 4452.799624016927, 4696.38946936896, 4722.763861806827, 4723.31511930775, 4750.192467280502, 4791.161546729239, 5093.745312734274, 5101.056304583664, 5115.411727651219, 5119.948800512097, 5131.255967884561, 4596.149712303235, 4691.366665500113, 4731.266653668687, 4827.806887724451, 4890.119778439773, 4974.5771609413605, 5002.699838009731, 5003.424606170337, 5008.0, 5032.421271553818, 5028.069807085701, 5074.416442109205, 5075.746212689277, 5083.92374114396, 5120.18077107661, 4664.277037059262, 4780.713157210578, 4806.519348065165, 4892.535209155125, 4925.088858561035, 4830.1305764961235, 4834.0, 4843.830465933675, 4854.660173787801, 4887.022595480989, 4737.786799593958, 4742.715437073786, 4744.146053710365, 4752.2158843790385, 4755.453122890912, 4762.285657151455, 4863.9513604864915, 4884.633652476007, 4891.0, 4951.579115775199, 4789.521047895322, 4794.640401969936, 4814.085323788469, 4824.782884770124, 4833.589144922721], "unit": "ms", "name": "dojo.html", "value": 4606.0810503726525}, {"lowerIsBetter": false, "replicates": [557.5177471487164, 566.5297802823657, 570.6604570280684, 572.2846441947565, 580.1964279472934, 33176.66823331766, 33607.83196084022, 33810.49284260735, 33811.83094084526, 33824.83087584565, 38487.61512384875, 39025.80487097568, 39100.21799564007, 39121.804390978075, 39160.60839391605, 7786.182450842651, 7880.960595197016, 7941.0, 7942.4043196760185, 7943.086545047307, 7781.961090194541, 7817.374610031212, 7867.134615192324, 7895.171007044264, 7896.881546776803, 7770.33952114071, 7839.0, 7839.6472158752995, 7852.725154619561, 7862.842743145134, 7849.843003139934, 7893.723719669784, 7904.565248911307, 7920.48516846406, 7936.960315198445, 7681.3854891608535, 7749.4187935904465, 7810.375169986388, 7811.4922530035365, 7860.606969651494, 36611.4508282376, 37143.814280928695, 37169.44245836315, 37179.0, 37191.44212836809, 2706.093458691341, 2762.875670594828, 2762.9723702763117, 2772.7227277272314, 2896.826190428581, 25437.364065898317, 29953.700462995308, 30301.393972120655, 30512.237194070327, 30682.386352273053, 3437.1063523483826, 3454.101933497284, 3484.4424892017155, 3515.1915059536195, 3567.233044895334, 206048.0, 206649.0, 208456.0, 208857.0, 208967.0, 46576.0, 47100.0, 47241.5275847244, 47708.284375734394, 55151.172732409046, 56535.71732141313, 56589.43410565924, 56619.15071273934, 56620.43379566234, 56919.14621280684, 13043.934780326037, 13044.804327935088, 13065.412056457482, 13090.869091309156, 13132.540361087507, 10659.840102398315, 10767.138628909668, 10782.35305881634, 10863.565457381615, 10972.0, 19002.90498547526, 19202.711959320623, 19289.421317360502, 19301.324453644054, 19330.226790928486, 7641.235876412426, 7808.882866757003, 7863.29230369258, 8003.639836207268, 8021.598920054091, 771.3944553525456, 795.0300633227454, 913.6619450803246, 960.9855852162223, 1033.26121822897, 25365.112221072173, 25403.364915876882, 25696.743032569437, 25702.100426484983, 25895.0, 967.8306296398102, 972.0376826941277, 997.3317877022413, 1012.6405126180247, 1097.8078836203633, 13345.9115729788, 13465.528706495421, 13531.526396576073, 13686.52097176594, 13810.447582096804, 309193.0, 311943.0, 313221.0, 314460.0, 314762.0, 46328.305075423894, 46545.0, 46552.7672361636, 46575.0, 46881.765591171825, 1221.7190046289224, 1249.9812502812465, 1251.4118364368733, 1260.691130672991, 1261.6845788552862, 24908.12821551237, 25358.239252822445, 25528.872355638105, 25533.489330213662, 25678.486430271663, 38284.234315312984, 38462.230755385295, 38842.22315553729, 38885.416718748675, 38895.6110438892, 235517.0, 236866.0, 237087.0, 237625.0, 239828.0, 29428.55857162187, 41833.581664182966, 42443.7877810609, 42846.14307713766, 43088.78455607702, 38725.6127438722, 38820.0, 39053.2189356217, 39903.0, 40071.599284006785, 33569.83215083909, 34382.0, 34436.48345274773, 34436.61237202565, 35175.296494070484, 34120.82939585286, 34121.146971324924, 34162.31675366528, 34550.65449345474, 34581.82709086438, 33591.66408335885, 33982.320353593284, 34111.829440852634, 34294.65705343014, 34462.65537344695, 30675.539866901567, 31113.688863111078, 31698.366032679678, 31842.522362164123, 31904.202394940312, 24482.877585611957, 30936.0, 31973.680263198, 31976.68023319737, 32099.67900320967], "unit": "ms", "name": "ext.html", "value": 17077.47561388317}, {"lowerIsBetter": false, "replicates": [324.5227388531881, 325.9266665000375, 336.1797214795898, 339.80800847521164, 340.6678488473738, 14921.850781492181, 15285.388584456623, 15816.0, 16457.0, 16752.413665521693, 10078.647247346344, 10224.897751022487, 13974.510892118778, 15562.37750489979, 15615.0, 4519.8418055368065, 4576.633869290449, 4648.047150334173, 4682.016776476927, 4682.367880336145, 4618.191816432128, 4662.487126416091, 4662.860114196579, 4666.416697912761, 4668.019715859674, 4640.373549570815, 4647.581717645411, 4920.6801557898625, 5100.030994111125, 5100.69395836251, 5005.224190250517, 5045.823396181116, 5058.418281897573, 5062.873428164288, 5108.182690769478, 5080.415752188509, 5086.109930762102, 5108.616853735947, 5122.718250496221, 5131.84604461863, 10966.122710183166, 11329.943350283278, 13186.340682965812, 17336.826631733646, 17436.302547898067, 1790.409164975568, 1863.1429542410508, 1929.1415320182525, 1938.709193620954, 1944.1445763863856, 8868.645254189825, 8870.955645221797, 8881.955590222073, 8971.416857904216, 9081.954590227073, 1739.2434291083418, 1767.920443580029, 1768.32803534656, 1807.7378780076815, 1814.637072585488, 52485.0, 55732.721336393464, 56399.43600563983, 56608.0, 56725.71637141788, 9580.712578622653, 9628.807423851624, 9884.851727224097, 9892.159166470787, 9927.50362481873, 12437.689057773447, 12477.376131193403, 12511.624651260478, 12525.311107889154, 12546.435410406553, 6736.326367363225, 6967.33810288032, 7013.89479157813, 7074.080369551925, 7119.00333953247, 6678.131842860397, 6726.058351830748, 6741.157355330583, 6775.864482710318, 6790.5925644461495, 4093.283675356801, 4142.150859073882, 4148.191102734938, 4149.439825623502, 4178.26880295947, 1697.3041053168142, 1716.957076073108, 1726.5597272695634, 1738.6001219719535, 1773.8314860088271, 725.575538310084, 776.720163061759, 823.3742355809628, 853.6756032707532, 885.1679421343916, 15013.774793378108, 15025.098494090389, 15717.842821571867, 15829.129397883227, 15832.445864394691, 830.2071521696765, 843.5360551696665, 853.4282031039219, 857.9356548258905, 872.3544577013091, 8351.457155284887, 8653.783655408664, 8913.955430222808, 8979.551022448852, 8993.325500587482, 448663.0, 451633.0, 456377.0, 457967.0, 463498.0, 67964.6601766988, 69745.30254697388, 71886.64056679684, 72048.63975680088, 73004.0, 1063.4895250279753, 1068.6259809066764, 1082.8971247731454, 1104.4422566603973, 1113.8774734779167, 13527.0, 13978.580642580739, 14511.172975691648, 14611.415543378145, 14756.0, 32461.513077303385, 32926.67073329332, 33192.33615327728, 33955.15112122217, 34172.48741268933, 41097.794511027256, 43065.0, 43212.783936081374, 44196.779016104716, 45512.54487455216, 8241.546714930737, 8686.04453510126, 8834.602442889956, 9012.098790120936, 9048.0, 10077.445740484343, 10235.539400726837, 10297.74255643615, 10372.896271037192, 12928.741425171633, 12030.819537707117, 12979.28613926243, 12994.350282486026, 13066.738665226832, 13507.797383039459, 12724.74550508966, 12945.676358091123, 12976.0, 13463.12489688167, 13788.0, 12754.68460018757, 13194.208347499181, 13274.0, 13319.0, 13363.331833408485, 8757.211850933447, 8955.910440895768, 8989.955050224708, 9067.138621830916, 9228.907710923073, 12457.314847683465, 13486.57183302148, 13491.797623035858, 13667.248301343521, 13777.173369597856], "unit": "ms", "name": "jquery.html", "value": 7996.395555197735}, {"lowerIsBetter": false, "replicates": [1484.4878516911674, 1627.5361521966238, 1697.312588401697, 1698.3800912666877, 1733.2893513659394, 5879.88240235195, 6231.189945307104, 6717.0, 7000.824979375512, 7056.047433596463, 6842.486813488996, 7039.190493093296, 7043.2956704329545, 7043.577385356883, 7082.96458517708, 1983.4743792894894, 2008.2569449303778, 2008.7489063867017, 2010.4270282969358, 2019.5960807838417, 2065.9690104648444, 2098.4229336932312, 2100.2649072824465, 2103.3584756649216, 2105.473631592102, 2074.1184996376555, 2095.8428117891217, 2096.968545471819, 2097.842661800371, 2106.9578608427823, 2070.81362677359, 2086.248950377863, 2098.2551194326024, 2099.4226587688354, 2103.915843366271, 2070.068469188871, 2093.8743675379374, 2117.269542008005, 2120.74551053873, 2140.1760322276036, 7106.822329441754, 7192.928070719278, 7197.820054498627, 7209.0628218331285, 7213.170485394195, 4203.327467605199, 4275.636570891478, 4280.721753086071, 4294.677899157576, 4304.849330273457, 2245.2141750387395, 2300.183434880627, 2765.834049957009, 2809.384603852772, 2811.4517669054544, 842.1283971089954, 847.8473874702551, 868.1145231863527, 889.6930558957149, 898.137787723786, 9510.09654082868, 9596.616135354578, 9658.71023869285, 9666.806663866753, 9681.564329605184, 3968.543617483969, 3974.8608798691907, 4082.3876418537457, 4103.5691252418665, 4110.17796440713, 7887.487313324616, 7961.164077771867, 8070.192980702001, 8076.71731489395, 8088.636011379502, 4267.65858731307, 4276.444062271885, 4293.205756934977, 4302.82788688449, 4312.417823593837, 3921.3725803871243, 3925.7644541327613, 3941.0, 3948.44721738951, 3959.9604003960167, 3138.814103684762, 3173.5715678383585, 3201.983990080035, 3211.9197020074216, 3222.5649537312634, 892.0410558649478, 901.8557030875029, 902.0483390023458, 902.5126431726925, 903.9457632542069, 762.0778857582387, 769.6344236487736, 775.0583041604477, 775.4571799740091, 887.6183241206214, 6459.09572659828, 6504.284528701839, 6511.38141876521, 6513.381228783258, 6526.2494813096155, 420.3274760383362, 428.1821720513866, 429.4910531020688, 430.07319227065096, 434.96737744669275, 5029.874253143701, 5050.974745126251, 5073.061483625467, 5109.6678715883345, 5126.359205099363, 7382.704691812375, 7439.516431432048, 8284.295834854105, 8328.208820162075, 8553.401261911606, 8117.107118216992, 8142.3486121111355, 8145.063317718538, 8186.549739764251, 8187.754367368989, 6728.865422691617, 6745.494087943423, 6846.4522838173825, 6888.035675005503, 6893.517453778188, 6720.8319792005595, 6729.0, 6731.32686731323, 6734.292899245509, 6779.389854913082, 7498.962505187439, 7598.924010759822, 7691.5769632670745, 7792.220777922163, 7870.645820937957, 7847.489913155627, 7910.841783164419, 7936.84126317482, 7956.482828616122, 8047.557384343683, 5913.763449461973, 5968.582199246011, 6012.188354572077, 6027.789027384196, 6036.6679832609625, 6892.069570607904, 6907.551009184387, 6943.444524438134, 6958.165020197609, 6965.0, 6434.613923164625, 6532.542722009413, 6571.507136964651, 6597.736090556516, 6683.832904177434, 6419.165508483868, 6456.9354306456335, 6515.543911926309, 6577.704003319766, 6625.304343043911, 6374.681265936777, 6400.327965563549, 6405.423511883952, 6406.903896441464, 6418.807435776934, 5656.547476201812, 5658.0, 5707.086866101404, 5710.200571920018, 5711.571632127524, 6053.031514957585, 6151.753929842756, 6192.566520343533, 6196.349383314687, 6210.534209934182], "unit": "ms", "name": "mootools.html", "value": 3931.7592849414236}, {"lowerIsBetter": false, "replicates": [279.55550674427656, 287.7151619896302, 287.97552208062314, 294.2599362603054, 297.95083811171156, 5487.451254874511, 5487.780488780454, 5539.1968164616155, 5559.249501317321, 5595.916061259074, 5465.535429488488, 5475.288212532365, 5496.532794712454, 5498.285222921015, 5509.944900550993, 4254.127903779718, 4254.936175957348, 4256.0, 4262.1688770689725, 4266.488021437432, 4256.7445953242905, 4260.595243451882, 4264.97867510662, 4265.0403659176745, 4277.187334406468, 3998.3202855514487, 4164.375343698439, 4216.97891510542, 4225.260579398609, 4226.281532139528, 4154.4183814266025, 4155.688323375759, 4157.293260145782, 4159.500859896817, 4170.291050521403, 3587.4798154267487, 3589.4436362363876, 3593.4070878305047, 3594.4788005739283, 3599.0642432967616, 5284.445133261029, 5299.072662284123, 5304.124819404794, 5311.840644780624, 5314.388845282765, 4619.0, 4700.1539722849875, 4778.378810754614, 4782.0, 4801.135795556798, 5634.211210430543, 5752.913706294409, 5760.711964401805, 5776.451237132465, 5791.507721843648, 3908.9445849620442, 3927.6857851372106, 3933.7443066200603, 3934.9606503935165, 3936.5079365079364, 5966.403359664042, 6061.454469097803, 6067.666278354689, 6074.271087469532, 6088.573799834014, 3943.6056394360335, 3996.620321069494, 4017.5580686124504, 4020.9396859047138, 4071.063655359284, 4096.365063415175, 4101.507819061672, 4107.815148318333, 4109.116539943926, 4114.1977314424, 4782.760861956888, 4785.976070119627, 4787.8563643090765, 4790.0, 4790.089882922233, 5781.508571771446, 5803.303603567514, 5804.448577385142, 5816.447437493432, 5830.41695830422, 1351.9797203041962, 1422.9644258893404, 1428.2787192467738, 1429.2639290765264, 1430.6852492451644, 871.4727589808201, 950.9429434233969, 952.9904700953041, 960.9471479068578, 977.8435450327913, 4976.7262800545595, 4984.501549845059, 4993.825216117419, 4994.650374473825, 5004.199328107486, 5967.403259673998, 5972.731227094791, 5984.102384642339, 5985.40145985398, 5989.431004054608, 4980.477049909779, 4986.177280748709, 4990.476000020019, 4995.125852975715, 5002.52476014778, 3096.210466331071, 3224.6452890182063, 3239.6760323967414, 3240.4977228529615, 3244.043007312848, 5564.360098588714, 5604.943950560524, 5613.691246981373, 5631.310606817939, 5640.492355688008, 6119.112728654409, 6136.8772624548155, 6140.232470941132, 6147.231596050494, 6155.84610384735, 5454.481824226692, 5583.553315734732, 5609.5792815539, 5618.297712785902, 5626.915596266142, 5946.73239704223, 5965.075413310944, 5966.731497082555, 5973.402659733992, 5982.760689572542, 5837.211976383134, 5841.707914604338, 5847.7368518417625, 5848.736806843617, 5859.619124757048, 5464.480874316933, 5572.86067848307, 5590.468905453975, 5590.860228494157, 5599.748011339418, 4023.8994025149605, 4032.0, 4034.91930161401, 4047.9797601011805, 4056.0671045659074, 5961.076033214858, 5966.075258334965, 5966.612170208922, 5967.1944287522365, 5976.820695379146, 5713.8285851424525, 5721.22763426932, 5721.284839395075, 5723.542116630742, 5730.713464326684, 5591.161325800999, 5728.513076388387, 5741.024025915688, 5742.856428589319, 5816.94183058164, 5643.266375371176, 5688.260526131577, 5689.630174038674, 5701.7149142541875, 5701.942980570308, 5704.971475142598, 5837.562182836219, 5859.853503662272, 5868.972929737364, 5893.263342082239, 5687.573431992534, 5698.857528561819, 5707.486326230659, 5737.34020587621, 5741.311042674739], "unit": "ms", "name": "prototype.html", "value": 4283.681090095112}, {"lowerIsBetter": false, "replicates": [571.6912867051792, 642.8585711143547, 669.8091044052444, 724.5000949344951, 731.7914394397598, 11268.380239086848, 11684.182107252496, 11699.532018719252, 11707.5902343418, 11715.062794976402, 11161.553537858475, 11943.283402995827, 11975.0, 11980.0, 12053.0, 616.8519555306716, 620.8789286089215, 769.0963118335961, 769.246138783992, 782.1513657681422, 790.4032455496092, 794.308951212446, 798.1738900238255, 799.952002879829, 800.1438460846896, 823.0411570520337, 826.8511667899776, 828.6561077152975, 829.9087100418949, 830.7424698343533, 824.3570015388007, 824.7979245084932, 825.1418524734299, 825.7233826668073, 825.9876101858476, 605.3583201806056, 610.5817515002237, 610.7495926670044, 611.6513587255266, 616.017452163798, 12156.088293378032, 12242.387880606024, 12306.138570300083, 12320.383980800923, 12403.0, 10420.374777513374, 10490.31812932157, 10537.104346130589, 10685.41230232337, 10854.403007834566, 8779.20987111163, 8861.867071993925, 8876.8668469973, 8927.687530936386, 8951.865722014176, 3188.689851410044, 3200.983995080033, 3230.353929214143, 3237.27161388688, 3240.335731175127, 40799.388009179886, 40834.38748418776, 40843.59156408428, 40898.386524202455, 41041.0, 9159.129882661136, 9205.723828285161, 9449.527523623792, 9562.28282878773, 9641.469719165372, 16359.672806543802, 16697.833021669627, 16714.916425417796, 16734.665306693798, 16735.49793506197, 6277.937220627735, 6330.936690633035, 6335.68321583919, 6335.968320158462, 6489.902651460232, 3474.0, 3568.1436455250555, 3600.3159399713973, 3654.6528079832374, 3662.8901132966053, 2603.0, 2647.3381654586356, 2654.853983030952, 2663.414048909237, 2668.2662267876103, 453.66882176011694, 459.2582978489715, 459.47160765119713, 477.40562999066384, 478.4067755982626, 463.0114705104626, 467.67496589869717, 485.085613618332, 506.67572753437827, 570.1590154522047, 7411.851762964819, 7518.473706840578, 7578.54528728267, 7606.087269527581, 7623.313901748869, 517.5161224255334, 563.6682098076296, 589.3340525206489, 596.1326270276737, 613.6318209074609, 3551.236484155919, 3669.9266014679556, 3734.421164719473, 3771.9434208486896, 3830.3296923038356, 49726.0, 50473.24290135651, 50533.24200137001, 50732.0, 51067.23399149016, 45811.77094114575, 46053.30920036202, 46390.30414543785, 46547.76726116348, 46737.0, 41553.37669934954, 41877.79061104675, 41989.79005104955, 42028.57971420247, 42128.78935605364, 6505.544611877123, 6611.66941652925, 6674.866502670016, 6736.7642132525125, 6794.286599906938, 31457.685423145474, 31593.52609710902, 32355.514667279538, 32439.351212975136, 33054.5041824368, 38656.80671596624, 39436.802815986885, 41071.589284106776, 42464.575354247296, 42952.0, 9598.472084035166, 9743.610255589698, 9760.56077476501, 9802.435400065617, 9960.900390995997, 11456.3698996556, 11514.53941842317, 11527.250728702607, 11647.59233426826, 11761.059115270587, 9284.0, 9960.50197490108, 10215.94892025535, 10666.146708263475, 10667.306625069346, 10101.14140298083, 10491.422971736627, 10497.685069447929, 10603.94698026505, 10941.781164376509, 9923.702288931343, 9939.204863611038, 10047.949760251153, 10282.948585257025, 10336.534855931352, 8914.37599368064, 9189.264858811146, 9262.814743704954, 9310.767230819283, 9320.161185493338, 8671.956640216758, 8797.921954078394, 9184.219341355792, 9293.349465537347, 9342.439453632804], "unit": "ms", "name": "yui.html", "value": 5629.170453956938}], "name": "dromaeo_css", "value": 6250.633227712836}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 107.0, 114.0, 114.0, 113.0, 126.0, 113.0, 114.0, 113.0, 120.0], "unit": "ms", "name": "ai-astar", "value": 114.7}, {"lowerIsBetter": true, "replicates": [163.0, 142.0, 159.0, 171.0, 132.0, 153.0, 153.0, 139.0, 166.0, 131.0], "unit": "ms", "name": "audio-beat-detection", "value": 150.9}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 161.0, 161.0, 164.0, 172.0, 165.0, 164.0, 166.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 164.1}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 71.0, 84.0, 136.0, 74.0, 77.0, 75.0, 73.0, 92.0], "unit": "ms", "name": "audio-fft", "value": 82.6}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 132.0, 95.0, 95.0, 95.0, 138.0, 97.0, 95.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 106.7}, {"lowerIsBetter": true, "replicates": [118.0, 111.0, 111.0, 111.0, 110.0, 110.0, 110.0, 110.0, 111.0, 111.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 111.3}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 149.0, 147.0, 148.0, 149.0, 150.0, 148.0, 148.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 148.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 71.0, 72.0, 72.0, 70.0, 71.0, 71.0, 73.0, 75.0], "unit": "ms", "name": "json-parse-financial", "value": 72.0}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 69.0, 69.0, 69.0, 69.0, 68.0, 70.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 84.0, 84.0, 85.0, 94.0, 83.0, 84.0, 98.0, 98.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.9}, {"lowerIsBetter": true, "replicates": [129.0, 128.0, 126.0, 119.0, 125.0, 129.0, 139.0, 125.0, 131.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 127.5}, {"lowerIsBetter": true, "replicates": [145.0, 149.0, 149.0, 154.0, 159.0, 144.0, 149.0, 148.0, 151.0, 145.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.3}, {"lowerIsBetter": true, "replicates": [73.0, 70.0, 72.0, 72.0, 70.0, 70.0, 69.0, 69.0, 68.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.2}], "name": "kraken", "value": 1550.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.7550140891762], "unit": "score", "name": "Richards", "value": 212.97696600000003}, {"lowerIsBetter": false, "replicates": [185.08236165093467], "unit": "score", "name": "DeltaBlue", "value": 357.23555399999987}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1072.961373390558], "unit": "score", "name": "RayTrace", "value": 689.6697479999999}, {"lowerIsBetter": false, "replicates": [710.7320540156361], "unit": "score", "name": "Earley", "value": 937.7134409999999}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [25846.153846153848], "unit": "score", "name": "RegExp", "value": 35.24644345238097}, {"lowerIsBetter": false, "replicates": [588.9281507656066], "unit": "score", "name": "Splay", "value": 138.37171800000004}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21429.6280691017}]} 09:43:05 INFO - RETURN: dromaeo_css: 6250.63 09:43:05 INFO - RETURN: kraken: 1550.40 09:43:05 INFO - RETURN: v8_7: 21429.63 09:43:05 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21429.63"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1550.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6250.63"}}} 09:43:05 INFO - Return code: 0 09:43:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:43:05 INFO - # TBPL SUCCESS # 09:43:05 INFO - Running post-action listener: _resource_record_post_action 09:43:05 INFO - Running post-run listener: _resource_record_post_run 09:43:06 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 36864; Write bytes: 516849664; Read time: 292; Write time: 455820 09:43:06 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 28672; Write bytes: 241774592; Read time: 84; Write time: 274988 09:43:06 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 8192; Write bytes: 275075072; Read time: 208; Write time: 180832 09:43:06 INFO - Running post-run listener: _upload_blobber_files 09:43:06 INFO - Blob upload gear active. 09:43:06 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:43:06 INFO - Copying logs to upload dir... 09:43:06 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1265.589739 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-12-03 09:43:06.601786) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-03 09:43:06.605619) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010143 build_url: 'https://queue.taskcluster.net/v1/task/_EK_P05MQauvEdKksLZgrA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-03 09:43:06.658466) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:43:06.658848) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1449163317.570488-781780605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004577 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-03 09:43:06.709626) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-03 09:43:06.709951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-03 09:43:06.710326) ========= ========= Total master_lag: 0.21 =========