builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1452346464.49 results: success (0) buildid: 20160109031532 builduid: aefdffd64f2049439d5882ed82ff816d revision: 098464f40ee9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.491728) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.492432) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.492974) ========= bash -c pwd in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006336 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.518902) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.519295) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.562511) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.562828) ========= rm -rf properties in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004661 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.612737) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.613086) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.613553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.613891) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-09 05:34:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.4M=0s 2016-01-09 05:34:24 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171294 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.798883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.799260) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015848 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.851377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:24.851837) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 098464f40ee9 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 098464f40ee9 --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-09 05:34:25,107 Setting DEBUG logging. 2016-01-09 05:34:25,107 attempt 1/10 2016-01-09 05:34:25,107 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/098464f40ee9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-09 05:34:25,467 unpacking tar archive at: mozilla-beta-098464f40ee9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658772 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:25.523725) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:25.524059) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:25.544446) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:25.544766) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-09 05:34:25.545205) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-01-09 05:34:25.545538) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:34:25 INFO - MultiFileLogger online at 20160109 05:34:25 in /builds/slave/test-pgo 05:34:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:34:25 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:34:25 INFO - {'append_to_log': False, 05:34:25 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:34:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:34:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:34:25 INFO - 'branch': 'Mozilla-Beta', 05:34:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:34:25 INFO - 'config_files': ('talos/linux_config.py',), 05:34:25 INFO - 'default_actions': ('clobber', 05:34:25 INFO - 'read-buildbot-config', 05:34:25 INFO - 'download-and-extract', 05:34:25 INFO - 'populate-webroot', 05:34:25 INFO - 'create-virtualenv', 05:34:25 INFO - 'install', 05:34:25 INFO - 'run-tests'), 05:34:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:34:25 INFO - 'download_minidump_stackwalk': True, 05:34:25 INFO - 'download_symbols': 'ondemand', 05:34:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:34:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:34:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:34:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:34:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:34:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:34:25 INFO - 'installer_path': 'installer.exe', 05:34:25 INFO - 'log_level': 'info', 05:34:25 INFO - 'log_name': 'talos', 05:34:25 INFO - 'log_to_console': True, 05:34:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:34:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:34:25 INFO - 'opt_config_files': (), 05:34:25 INFO - 'pip_index': False, 05:34:25 INFO - 'sps_profile': False, 05:34:25 INFO - 'sps_profile_interval': 0, 05:34:25 INFO - 'suite': 'dromaeojs', 05:34:25 INFO - 'system_bits': '32', 05:34:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:34:25 INFO - 'title': 'talos-linux64-ix-023', 05:34:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:34:25 INFO - 'use_talos_json': True, 05:34:25 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:34:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:34:25 INFO - 'work_dir': 'build'} 05:34:25 INFO - ##### 05:34:25 INFO - ##### Running clobber step. 05:34:25 INFO - ##### 05:34:25 INFO - Running pre-action listener: _resource_record_pre_action 05:34:25 INFO - Running main action method: clobber 05:34:25 INFO - rmtree: /builds/slave/test-pgo/build 05:34:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:34:26 INFO - Running post-action listener: _resource_record_post_action 05:34:26 INFO - ##### 05:34:26 INFO - ##### Running read-buildbot-config step. 05:34:26 INFO - ##### 05:34:26 INFO - Running pre-action listener: _resource_record_pre_action 05:34:26 INFO - Running main action method: read_buildbot_config 05:34:26 INFO - Using buildbot properties: 05:34:26 INFO - { 05:34:26 INFO - "properties": { 05:34:26 INFO - "buildnumber": 83, 05:34:26 INFO - "product": "firefox", 05:34:26 INFO - "script_repo_revision": "production", 05:34:26 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:34:26 INFO - "repository": "", 05:34:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:34:26 INFO - "buildid": "20160109031532", 05:34:26 INFO - "slavename": "talos-linux64-ix-023", 05:34:26 INFO - "pgo_build": "True", 05:34:26 INFO - "basedir": "/builds/slave/test-pgo", 05:34:26 INFO - "project": "", 05:34:26 INFO - "platform": "ubuntu64_hw", 05:34:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:34:26 INFO - "slavebuilddir": "test", 05:34:26 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:34:26 INFO - "branch": "mozilla-beta", 05:34:26 INFO - "repo_path": "releases/mozilla-beta", 05:34:26 INFO - "stage_platform": "linux64-pgo", 05:34:26 INFO - "builduid": "aefdffd64f2049439d5882ed82ff816d", 05:34:26 INFO - "revision": "098464f40ee9" 05:34:26 INFO - }, 05:34:26 INFO - "sourcestamp": { 05:34:26 INFO - "repository": "", 05:34:26 INFO - "hasPatch": false, 05:34:26 INFO - "project": "", 05:34:26 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 05:34:26 INFO - "changes": [ 05:34:26 INFO - { 05:34:26 INFO - "category": null, 05:34:26 INFO - "files": [ 05:34:26 INFO - { 05:34:26 INFO - "url": null, 05:34:26 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2" 05:34:26 INFO - } 05:34:26 INFO - ], 05:34:26 INFO - "repository": "", 05:34:26 INFO - "rev": "098464f40ee9", 05:34:26 INFO - "who": "sendchange", 05:34:26 INFO - "when": 1452346448, 05:34:26 INFO - "number": 7438993, 05:34:26 INFO - "comments": "No bug, Automated blocklist update from host bld-linux64-spot-301 - a=blocklist-update", 05:34:26 INFO - "project": "", 05:34:26 INFO - "at": "Sat 09 Jan 2016 05:34:08", 05:34:26 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 05:34:26 INFO - "revlink": "", 05:34:26 INFO - "properties": [ 05:34:26 INFO - [ 05:34:26 INFO - "buildid", 05:34:26 INFO - "20160109031532", 05:34:26 INFO - "Change" 05:34:26 INFO - ], 05:34:26 INFO - [ 05:34:26 INFO - "builduid", 05:34:26 INFO - "aefdffd64f2049439d5882ed82ff816d", 05:34:26 INFO - "Change" 05:34:26 INFO - ], 05:34:26 INFO - [ 05:34:26 INFO - "pgo_build", 05:34:26 INFO - "True", 05:34:26 INFO - "Change" 05:34:26 INFO - ] 05:34:26 INFO - ], 05:34:26 INFO - "revision": "098464f40ee9" 05:34:26 INFO - } 05:34:26 INFO - ], 05:34:26 INFO - "revision": "098464f40ee9" 05:34:26 INFO - } 05:34:26 INFO - } 05:34:26 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2. 05:34:26 INFO - Running post-action listener: _resource_record_post_action 05:34:26 INFO - ##### 05:34:26 INFO - ##### Running download-and-extract step. 05:34:26 INFO - ##### 05:34:26 INFO - Running pre-action listener: _resource_record_pre_action 05:34:26 INFO - Running main action method: download_and_extract 05:34:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/test_packages.json 05:34:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:34:26 INFO - Downloaded 1270 bytes. 05:34:26 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:34:26 INFO - Using the following test package requirements: 05:34:26 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 05:34:26 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:34:26 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'jsshell-linux-x86_64.zip'], 05:34:26 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:34:26 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 05:34:26 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 05:34:26 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 05:34:26 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:34:26 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 05:34:26 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 05:34:26 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:34:26 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.common.tests.zip 05:34:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 05:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:34:29 INFO - Downloaded 22572409 bytes. 05:34:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:34:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 05:34:29 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:34:30 INFO - Return code: 0 05:34:30 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:34: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')]} 05:34:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.common.tests.zip 05:34:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 05:34:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:34:31 INFO - Downloaded 22572409 bytes. 05:34:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:34:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 05:34:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:34:32 INFO - Return code: 0 05:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 05:34:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 05:34:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:34:34 INFO - Downloaded 11131970 bytes. 05:34:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:34:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 05:34:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:34:35 INFO - Return code: 0 05:34:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2 05:34:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 05:34:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:34:40 INFO - Downloaded 56629855 bytes. 05:34:40 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2 05:34:40 INFO - mkdir: /builds/slave/test-pgo/properties 05:34:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:34:40 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:34:40 INFO - Contents: 05:34:40 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2 05:34:40 INFO - Running post-action listener: _resource_record_post_action 05:34:40 INFO - Running post-action listener: set_extra_try_arguments 05:34:40 INFO - ##### 05:34:40 INFO - ##### Running populate-webroot step. 05:34:40 INFO - ##### 05:34:40 INFO - Running pre-action listener: _resource_record_pre_action 05:34:40 INFO - Running main action method: populate_webroot 05:34:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:34:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:34:40 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:34:40 INFO - '../fennec_ids.txt'], 05:34:40 INFO - 'tests': ['tcheck2']}, 05:34:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:34:40 INFO - '--tppagecycles', 05:34:40 INFO - '7'], 05:34:40 INFO - 'tests': ['tsvgm']}}, 05:34:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:34:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:34:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:34:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:34:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:34:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:34:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'tests': ['damp', 'tps']}, 05:34:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['damp', 'tps']}, 05:34:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:34:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:34:40 INFO - 'other': {'tests': ['a11yr', 05:34:40 INFO - 'ts_paint', 05:34:40 INFO - 'tpaint', 05:34:40 INFO - 'sessionrestore', 05:34:40 INFO - 'sessionrestore_no_auto_restore']}, 05:34:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['a11yr', 05:34:40 INFO - 'ts_paint', 05:34:40 INFO - 'tpaint', 05:34:40 INFO - 'sessionrestore', 05:34:40 INFO - 'sessionrestore_no_auto_restore']}, 05:34:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['a11yr', 05:34:40 INFO - 'ts_paint', 05:34:40 INFO - 'tpaint', 05:34:40 INFO - 'sessionrestore', 05:34:40 INFO - 'sessionrestore_no_auto_restore']}, 05:34:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['a11yr', 05:34:40 INFO - 'ts_paint', 05:34:40 INFO - 'tpaint', 05:34:40 INFO - 'sessionrestore', 05:34:40 INFO - 'sessionrestore_no_auto_restore']}, 05:34:40 INFO - 'other_l64': {'tests': ['a11yr', 05:34:40 INFO - 'ts_paint', 05:34:40 INFO - 'tpaint', 05:34:40 INFO - 'sessionrestore', 05:34:40 INFO - 'sessionrestore_no_auto_restore']}, 05:34:40 INFO - 'other_nol64': {'tests': ['a11yr', 05:34:40 INFO - 'ts_paint', 05:34:40 INFO - 'tpaint', 05:34:40 INFO - 'sessionrestore', 05:34:40 INFO - 'sessionrestore_no_auto_restore']}, 05:34:40 INFO - 'svgr': {'tests': ['tsvgx', 05:34:40 INFO - 'tsvgr_opacity', 05:34:40 INFO - 'tart', 05:34:40 INFO - 'tscrollx', 05:34:40 INFO - 'cart']}, 05:34:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['tsvgx', 05:34:40 INFO - 'tsvgr_opacity', 05:34:40 INFO - 'tart', 05:34:40 INFO - 'tscrollx', 05:34:40 INFO - 'cart']}, 05:34:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'tests': ['tp5o']}, 05:34:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'talos_options': ['--e10s'], 05:34:40 INFO - 'tests': ['tp5o']}, 05:34:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'talos_options': ['--xperf_path', 05:34:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:34:40 INFO - 'tests': ['tp5n']}, 05:34:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:40 INFO - 'talos_options': ['--e10s', 05:34:40 INFO - '--xperf_path', 05:34:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:34:40 INFO - 'tests': ['tp5n']}}, 05:34:40 INFO - 'talos.zip': {'path': '', 05:34:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:34:40 INFO - Running post-action listener: _resource_record_post_action 05:34:40 INFO - ##### 05:34:40 INFO - ##### Running create-virtualenv step. 05:34:40 INFO - ##### 05:34:40 INFO - Running pre-action listener: _resource_record_pre_action 05:34:40 INFO - Running main action method: create_virtualenv 05:34:40 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:34:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:34:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:34:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:34:40 INFO - Using real prefix '/usr' 05:34:40 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:34:40 INFO - Installing distribute.............................................................................................................................................................................................done. 05:34:42 INFO - Installing pip.................done. 05:34:42 INFO - Return code: 0 05:34:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 05:34:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:34:42 INFO - Using env: {'DISPLAY': ':0', 05:34:42 INFO - 'HOME': '/home/cltbld', 05:34:42 INFO - 'LANG': 'en_US.UTF-8', 05:34:42 INFO - 'LANGUAGE': 'en_US:en', 05:34:42 INFO - 'LOGNAME': 'cltbld', 05:34:42 INFO - 'MAIL': '/var/mail/cltbld', 05:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:42 INFO - 'MOZ_NO_REMOTE': '1', 05:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:42 INFO - 'NO_EM_RESTART': '1', 05:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:42 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:42 INFO - 'SHELL': '/bin/bash', 05:34:42 INFO - 'SHLVL': '1', 05:34:42 INFO - 'TERM': 'linux', 05:34:42 INFO - 'TMOUT': '86400', 05:34:42 INFO - 'USER': 'cltbld', 05:34:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:42 INFO - '_': '/tools/buildbot/bin/python'} 05:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:34:42 INFO - Cleaning up... 05:34:42 INFO - Return code: 0 05:34:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 05:34:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:34:42 INFO - Using env: {'DISPLAY': ':0', 05:34:42 INFO - 'HOME': '/home/cltbld', 05:34:42 INFO - 'LANG': 'en_US.UTF-8', 05:34:42 INFO - 'LANGUAGE': 'en_US:en', 05:34:42 INFO - 'LOGNAME': 'cltbld', 05:34:42 INFO - 'MAIL': '/var/mail/cltbld', 05:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:42 INFO - 'MOZ_NO_REMOTE': '1', 05:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:42 INFO - 'NO_EM_RESTART': '1', 05:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:42 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:42 INFO - 'SHELL': '/bin/bash', 05:34:42 INFO - 'SHLVL': '1', 05:34:42 INFO - 'TERM': 'linux', 05:34:42 INFO - 'TMOUT': '86400', 05:34:42 INFO - 'USER': 'cltbld', 05:34:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:42 INFO - '_': '/tools/buildbot/bin/python'} 05:34:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:43 INFO - Downloading/unpacking psutil>=0.7.1 05:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:44 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:34:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:34:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:34:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:34:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:34:44 INFO - Installing collected packages: psutil 05:34:44 INFO - Running setup.py install for psutil 05:34:44 INFO - building 'psutil._psutil_linux' extension 05:34:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:34:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:34:45 INFO - building 'psutil._psutil_posix' extension 05:34:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:34:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:34:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:34:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:34:45 INFO - Successfully installed psutil 05:34:45 INFO - Cleaning up... 05:34:45 INFO - Return code: 0 05:34:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 05:34:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:34:45 INFO - Using env: {'DISPLAY': ':0', 05:34:45 INFO - 'HOME': '/home/cltbld', 05:34:45 INFO - 'LANG': 'en_US.UTF-8', 05:34:45 INFO - 'LANGUAGE': 'en_US:en', 05:34:45 INFO - 'LOGNAME': 'cltbld', 05:34:45 INFO - 'MAIL': '/var/mail/cltbld', 05:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:45 INFO - 'MOZ_NO_REMOTE': '1', 05:34:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:45 INFO - 'NO_EM_RESTART': '1', 05:34:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:45 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:45 INFO - 'SHELL': '/bin/bash', 05:34:45 INFO - 'SHLVL': '1', 05:34:45 INFO - 'TERM': 'linux', 05:34:45 INFO - 'TMOUT': '86400', 05:34:45 INFO - 'USER': 'cltbld', 05:34:45 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:45 INFO - '_': '/tools/buildbot/bin/python'} 05:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:34:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:34:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:34:47 INFO - Installing collected packages: mozsystemmonitor 05:34:47 INFO - Running setup.py install for mozsystemmonitor 05:34:47 INFO - Successfully installed mozsystemmonitor 05:34:47 INFO - Cleaning up... 05:34:47 INFO - Return code: 0 05:34:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 05:34:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:34:47 INFO - Using env: {'DISPLAY': ':0', 05:34:47 INFO - 'HOME': '/home/cltbld', 05:34:47 INFO - 'LANG': 'en_US.UTF-8', 05:34:47 INFO - 'LANGUAGE': 'en_US:en', 05:34:47 INFO - 'LOGNAME': 'cltbld', 05:34:47 INFO - 'MAIL': '/var/mail/cltbld', 05:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:47 INFO - 'MOZ_NO_REMOTE': '1', 05:34:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:47 INFO - 'NO_EM_RESTART': '1', 05:34:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:47 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:47 INFO - 'SHELL': '/bin/bash', 05:34:47 INFO - 'SHLVL': '1', 05:34:47 INFO - 'TERM': 'linux', 05:34:47 INFO - 'TMOUT': '86400', 05:34:47 INFO - 'USER': 'cltbld', 05:34:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:47 INFO - '_': '/tools/buildbot/bin/python'} 05:34:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:47 INFO - Downloading/unpacking blobuploader==1.2.4 05:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:49 INFO - Downloading blobuploader-1.2.4.tar.gz 05:34:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:34:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:34:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:34:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:34:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:50 INFO - Downloading docopt-0.6.1.tar.gz 05:34:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:34:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:34:50 INFO - Installing collected packages: blobuploader, requests, docopt 05:34:50 INFO - Running setup.py install for blobuploader 05:34:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:34:50 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:34:50 INFO - Running setup.py install for requests 05:34:50 INFO - Running setup.py install for docopt 05:34:50 INFO - Successfully installed blobuploader requests docopt 05:34:50 INFO - Cleaning up... 05:34:50 INFO - Return code: 0 05:34:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 05:34:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:34:50 INFO - Using env: {'DISPLAY': ':0', 05:34:50 INFO - 'HOME': '/home/cltbld', 05:34:50 INFO - 'LANG': 'en_US.UTF-8', 05:34:50 INFO - 'LANGUAGE': 'en_US:en', 05:34:50 INFO - 'LOGNAME': 'cltbld', 05:34:50 INFO - 'MAIL': '/var/mail/cltbld', 05:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:50 INFO - 'MOZ_NO_REMOTE': '1', 05:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:50 INFO - 'NO_EM_RESTART': '1', 05:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:50 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:50 INFO - 'SHELL': '/bin/bash', 05:34:50 INFO - 'SHLVL': '1', 05:34:50 INFO - 'TERM': 'linux', 05:34:50 INFO - 'TMOUT': '86400', 05:34:50 INFO - 'USER': 'cltbld', 05:34:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:50 INFO - '_': '/tools/buildbot/bin/python'} 05:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:34:50 INFO - Running setup.py (path:/tmp/pip-4rdvjA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:34:51 INFO - Running setup.py (path:/tmp/pip-FHDFrv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:34:51 INFO - Running setup.py (path:/tmp/pip-Qc2UZw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:34:51 INFO - Running setup.py (path:/tmp/pip-P5DbpV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:34:51 INFO - Running setup.py (path:/tmp/pip-LcYbYi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:34:51 INFO - Running setup.py (path:/tmp/pip-6hkVxh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:34:51 INFO - Running setup.py (path:/tmp/pip-29EQYi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:34:51 INFO - Running setup.py (path:/tmp/pip-PHKi7p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:34:51 INFO - Running setup.py (path:/tmp/pip-kCkJz3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:34:51 INFO - Running setup.py (path:/tmp/pip-1wQYHY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:34:51 INFO - Running setup.py (path:/tmp/pip-wnmskb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:34:51 INFO - Running setup.py (path:/tmp/pip-PSBYWD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:34:51 INFO - Running setup.py (path:/tmp/pip-TNE56Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:34:51 INFO - Running setup.py (path:/tmp/pip-wimV6o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:34:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:34:51 INFO - Running setup.py (path:/tmp/pip-oyBymT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:34:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:34:52 INFO - Running setup.py (path:/tmp/pip-00YsXS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:34:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:34:52 INFO - Running setup.py (path:/tmp/pip-ZGV_he-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:34:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:34:52 INFO - Running setup.py install for manifestparser 05:34:52 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Running setup.py install for mozcrash 05:34:52 INFO - Running setup.py install for mozdebug 05:34:52 INFO - Running setup.py install for mozdevice 05:34:52 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Running setup.py install for mozfile 05:34:52 INFO - Running setup.py install for mozhttpd 05:34:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Running setup.py install for mozinfo 05:34:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Running setup.py install for mozInstall 05:34:52 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:34:52 INFO - Running setup.py install for mozleak 05:34:53 INFO - Running setup.py install for mozlog 05:34:53 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Running setup.py install for moznetwork 05:34:53 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Running setup.py install for mozprocess 05:34:53 INFO - Running setup.py install for mozprofile 05:34:53 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Running setup.py install for mozrunner 05:34:53 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Running setup.py install for mozscreenshot 05:34:53 INFO - Running setup.py install for moztest 05:34:53 INFO - Running setup.py install for mozversion 05:34:53 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:34:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:34:53 INFO - Cleaning up... 05:34:53 INFO - Return code: 0 05:34:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 05:34:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:34:53 INFO - Using env: {'DISPLAY': ':0', 05:34:53 INFO - 'HOME': '/home/cltbld', 05:34:53 INFO - 'LANG': 'en_US.UTF-8', 05:34:53 INFO - 'LANGUAGE': 'en_US:en', 05:34:53 INFO - 'LOGNAME': 'cltbld', 05:34:53 INFO - 'MAIL': '/var/mail/cltbld', 05:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:53 INFO - 'MOZ_NO_REMOTE': '1', 05:34:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:53 INFO - 'NO_EM_RESTART': '1', 05:34:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:53 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:53 INFO - 'SHELL': '/bin/bash', 05:34:53 INFO - 'SHLVL': '1', 05:34:53 INFO - 'TERM': 'linux', 05:34:53 INFO - 'TMOUT': '86400', 05:34:53 INFO - 'USER': 'cltbld', 05:34:53 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:53 INFO - '_': '/tools/buildbot/bin/python'} 05:34:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:34:54 INFO - Running setup.py (path:/tmp/pip-v4zn6H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:34:54 INFO - Running setup.py (path:/tmp/pip-eB2pPB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:34:54 INFO - Running setup.py (path:/tmp/pip-8L6pEO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:34:54 INFO - Running setup.py (path:/tmp/pip-3MDiRa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:34:54 INFO - Running setup.py (path:/tmp/pip-V1gL6P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:34:54 INFO - Running setup.py (path:/tmp/pip-0LS4oy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:34:54 INFO - Running setup.py (path:/tmp/pip-VgWkqU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:34:54 INFO - Running setup.py (path:/tmp/pip-OCbJWp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:34:54 INFO - Running setup.py (path:/tmp/pip-vHf_oB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:34:54 INFO - Running setup.py (path:/tmp/pip-MlR_V5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:34:54 INFO - Running setup.py (path:/tmp/pip-xIrgAO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:34:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:34:54 INFO - Running setup.py (path:/tmp/pip-OFZrpl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:34:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:34:55 INFO - Running setup.py (path:/tmp/pip-IKqUtf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:34:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:34:55 INFO - Running setup.py (path:/tmp/pip-e2eg8v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:34:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:34:55 INFO - Running setup.py (path:/tmp/pip-2DcARP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:34:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:34:55 INFO - Running setup.py (path:/tmp/pip-E_J4ma-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:34:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:34:55 INFO - Running setup.py (path:/tmp/pip-heZQrm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:34:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:57 INFO - Downloading blessings-1.5.1.tar.gz 05:34:57 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:34:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:34:57 INFO - Installing collected packages: blessings 05:34:57 INFO - Running setup.py install for blessings 05:34:57 INFO - Successfully installed blessings 05:34:57 INFO - Cleaning up... 05:34:57 INFO - Return code: 0 05:34:57 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:34:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:34:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:34:57 INFO - Reading from file tmpfile_stdout 05:34:57 INFO - Current package versions: 05:34:57 INFO - argparse == 1.2.1 05:34:57 INFO - blessings == 1.5.1 05:34:57 INFO - blobuploader == 1.2.4 05:34:57 INFO - docopt == 0.6.1 05:34:57 INFO - manifestparser == 1.1 05:34:57 INFO - mozInstall == 1.12 05:34:57 INFO - mozcrash == 0.16 05:34:57 INFO - mozdebug == 0.1 05:34:57 INFO - mozdevice == 0.46 05:34:57 INFO - mozfile == 1.2 05:34:57 INFO - mozhttpd == 0.7 05:34:57 INFO - mozinfo == 0.8 05:34:57 INFO - mozleak == 0.1 05:34:57 INFO - mozlog == 3.0 05:34:57 INFO - moznetwork == 0.27 05:34:57 INFO - mozprocess == 0.22 05:34:57 INFO - mozprofile == 0.27 05:34:57 INFO - mozrunner == 6.11 05:34:57 INFO - mozscreenshot == 0.1 05:34:57 INFO - mozsystemmonitor == 0.0 05:34:57 INFO - moztest == 0.7 05:34:57 INFO - mozversion == 1.4 05:34:57 INFO - psutil == 3.1.1 05:34:57 INFO - requests == 1.2.3 05:34:57 INFO - wsgiref == 0.1.2 05:34:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 05:34:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:34:57 INFO - Using env: {'DISPLAY': ':0', 05:34:57 INFO - 'HOME': '/home/cltbld', 05:34:57 INFO - 'LANG': 'en_US.UTF-8', 05:34:57 INFO - 'LANGUAGE': 'en_US:en', 05:34:57 INFO - 'LOGNAME': 'cltbld', 05:34:57 INFO - 'MAIL': '/var/mail/cltbld', 05:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:57 INFO - 'MOZ_NO_REMOTE': '1', 05:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:57 INFO - 'NO_EM_RESTART': '1', 05:34:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:57 INFO - 'SHELL': '/bin/bash', 05:34:57 INFO - 'SHLVL': '1', 05:34:57 INFO - 'TERM': 'linux', 05:34:57 INFO - 'TMOUT': '86400', 05:34:57 INFO - 'USER': 'cltbld', 05:34:57 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:57 INFO - '_': '/tools/buildbot/bin/python'} 05:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:34:57 INFO - Cleaning up... 05:34:57 INFO - Return code: 0 05:34:57 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:34:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 05:34:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:34:57 INFO - Using env: {'DISPLAY': ':0', 05:34:57 INFO - 'HOME': '/home/cltbld', 05:34:57 INFO - 'LANG': 'en_US.UTF-8', 05:34:57 INFO - 'LANGUAGE': 'en_US:en', 05:34:57 INFO - 'LOGNAME': 'cltbld', 05:34:57 INFO - 'MAIL': '/var/mail/cltbld', 05:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:57 INFO - 'MOZ_NO_REMOTE': '1', 05:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:57 INFO - 'NO_EM_RESTART': '1', 05:34:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:34:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:34:57 INFO - 'SHELL': '/bin/bash', 05:34:57 INFO - 'SHLVL': '1', 05:34:57 INFO - 'TERM': 'linux', 05:34:57 INFO - 'TMOUT': '86400', 05:34:57 INFO - 'USER': 'cltbld', 05:34:57 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:57 INFO - '_': '/tools/buildbot/bin/python'} 05:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:57 INFO - Downloading/unpacking jsonschema 05:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:34:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:34:59 INFO - Downloading/unpacking functools32 (from jsonschema) 05:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:59 INFO - Downloading functools32-3.2.3-2.tar.gz 05:34:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:34:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:34:59 INFO - warning: no files found matching '*.txt' 05:34:59 INFO - no previously-included directories found matching 'build' 05:34:59 INFO - no previously-included directories found matching 'dist' 05:34:59 INFO - no previously-included directories found matching '.git*' 05:34:59 INFO - Installing collected packages: jsonschema, functools32 05:34:59 INFO - Running setup.py install for functools32 05:35:00 INFO - warning: no files found matching '*.txt' 05:35:00 INFO - no previously-included directories found matching 'build' 05:35:00 INFO - no previously-included directories found matching 'dist' 05:35:00 INFO - no previously-included directories found matching '.git*' 05:35:00 INFO - Successfully installed jsonschema functools32 05:35:00 INFO - Cleaning up... 05:35:00 INFO - Return code: 0 05:35:00 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x7fd0d414df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a64600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:35:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 05:35:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:35:00 INFO - Using env: {'DISPLAY': ':0', 05:35:00 INFO - 'HOME': '/home/cltbld', 05:35:00 INFO - 'LANG': 'en_US.UTF-8', 05:35:00 INFO - 'LANGUAGE': 'en_US:en', 05:35:00 INFO - 'LOGNAME': 'cltbld', 05:35:00 INFO - 'MAIL': '/var/mail/cltbld', 05:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:00 INFO - 'MOZ_NO_REMOTE': '1', 05:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:35:00 INFO - 'NO_EM_RESTART': '1', 05:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:35:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:35:00 INFO - 'SHELL': '/bin/bash', 05:35:00 INFO - 'SHLVL': '1', 05:35:00 INFO - 'TERM': 'linux', 05:35:00 INFO - 'TMOUT': '86400', 05:35:00 INFO - 'USER': 'cltbld', 05:35:00 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:00 INFO - '_': '/tools/buildbot/bin/python'} 05:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:00 INFO - Downloading/unpacking flake8 05:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:35:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:35:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:35:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:35:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:35:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:35:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:35:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:35:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:35:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:35:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:35:02 INFO - no previously-included directories found matching 'docs/_build' 05:35:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:35:02 INFO - Running setup.py install for pep8 05:35:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:35:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:35:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:35:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:35:02 INFO - no previously-included directories found matching 'docs/_build' 05:35:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:35:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:35:02 INFO - Cleaning up... 05:35:02 INFO - Return code: 0 05:35:02 INFO - Running post-action listener: _resource_record_post_action 05:35:02 INFO - Running post-action listener: _start_resource_monitoring 05:35:02 INFO - Starting resource monitoring. 05:35:02 INFO - ##### 05:35:02 INFO - ##### Running install step. 05:35:02 INFO - ##### 05:35:02 INFO - Running pre-action listener: _resource_record_pre_action 05:35:02 INFO - Running main action method: install 05:35:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:35:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:35:03 INFO - Reading from file tmpfile_stdout 05:35:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:35:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:35:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:35:03 INFO - Reading from file tmpfile_stdout 05:35:03 INFO - Output received: 05:35:03 INFO - Usage: mozinstall [options] installer 05:35:03 INFO - Options: 05:35:03 INFO - -h, --help show this help message and exit 05:35:03 INFO - -d DEST, --destination=DEST 05:35:03 INFO - Directory to install application into. [default: 05:35:03 INFO - "/builds/slave/test-pgo"] 05:35:03 INFO - --app=APP Application being installed. [default: firefox] 05:35:03 INFO - mkdir: /builds/slave/test-pgo/build/application 05:35:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:35:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:35:17 INFO - Reading from file tmpfile_stdout 05:35:17 INFO - Output received: 05:35:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:35:17 INFO - Running post-action listener: _resource_record_post_action 05:35:17 INFO - ##### 05:35:17 INFO - ##### Running run-tests step. 05:35:17 INFO - ##### 05:35:17 INFO - Running pre-action listener: _resource_record_pre_action 05:35:17 INFO - Running main action method: run_tests 05:35:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:35:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:35:17 INFO - Python 2.7.3 05:35:17 INFO - Return code: 0 05:35:17 INFO - grabbing minidump binary from tooltool 05:35: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')]} 05:35:17 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-pgo/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 0x2af3350>, '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 0x2af4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af46e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:35:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:35:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:35:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:35:17 INFO - Return code: 0 05:35:17 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:35:17 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:35:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:35:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:35:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:35:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:35:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:35:18 INFO - Return code: 0 05:35:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:35:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost 05:35:18 INFO - Using env: {'DISPLAY': ':0', 05:35:18 INFO - 'HOME': '/home/cltbld', 05:35:18 INFO - 'LANG': 'en_US.UTF-8', 05:35:18 INFO - 'LANGUAGE': 'en_US:en', 05:35:18 INFO - 'LOGNAME': 'cltbld', 05:35:18 INFO - 'MAIL': '/var/mail/cltbld', 05:35:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:35:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:18 INFO - 'MOZ_NO_REMOTE': '1', 05:35:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:35:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:35:18 INFO - 'NO_EM_RESTART': '1', 05:35:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:35:18 INFO - 'PWD': '/builds/slave/test-pgo', 05:35:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:35:18 INFO - 'SHELL': '/bin/bash', 05:35:18 INFO - 'SHLVL': '1', 05:35:18 INFO - 'TERM': 'linux', 05:35:18 INFO - 'TMOUT': '86400', 05:35:18 INFO - 'USER': 'cltbld', 05:35:18 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084', 05:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:18 INFO - '_': '/tools/buildbot/bin/python'} 05:35:18 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost'] with output_timeout 3600 05:35:18 INFO - mozversion INFO | application_buildid: 20160109031532 05:35:18 INFO - mozversion INFO | application_changeset: 098464f40ee921e2a4be9d42e528351619a806ab 05:35:18 INFO - mozversion INFO | application_display_name: Nightly 05:35:18 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:35:18 INFO - mozversion INFO | application_name: Firefox 05:35:18 INFO - mozversion INFO | application_remotingname: firefox 05:35:18 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:35:18 INFO - mozversion INFO | application_vendor: Mozilla 05:35:18 INFO - mozversion INFO | application_version: 44.0 05:35:18 INFO - mozversion INFO | platform_buildid: 20160109031532 05:35:18 INFO - mozversion INFO | platform_changeset: 098464f40ee921e2a4be9d42e528351619a806ab 05:35:18 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:35:18 INFO - mozversion INFO | platform_version: 44.0 05:35:18 INFO - 2016-01-09 05:35:18,663 DEBUG : using testdate: 1452346518 05:35:18 INFO - 2016-01-09 05:35:18,663 DEBUG : actual date: 1452346518 05:35:18 INFO - 2016-01-09 05:35:18,763 INFO : starting webserver on 'localhost:59838' 05:35:18 INFO - 2016-01-09 05:35:18,765 INFO : Starting test suite talos-linux64-ix-023 05:35:18 INFO - 2016-01-09 05:35:18,765 INFO : Starting test dromaeo_css 05:35:18 INFO - 2016-01-09 05:35:18,765 DEBUG : operating with platform_type : linux_ 05:35:18 INFO - 2016-01-09 05:35:18,766 INFO : Initialising browser for dromaeo_css test... 05:35:18 INFO - 2016-01-09 05:35:18,782 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9UiDq1/profile http://localhost:59838/getInfo.html 05:35:22 INFO - 2016-01-09 05:35:22,177 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:35:22 INFO - 2016-01-09 05:35:22,178 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:35:22 INFO - 2016-01-09 05:35:22,178 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:35:22 INFO - 2016-01-09 05:35:22,178 DEBUG : BROWSER_OUTPUT: __metrics 05:35:22 INFO - 2016-01-09 05:35:22,266 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:35:23 INFO - 2016-01-09 05:35:23,485 INFO : Browser initialized. 05:35:23 INFO - 2016-01-09 05:35:23,485 INFO : Running cycle 1/1 for dromaeo_css test... 05:35:23 INFO - 2016-01-09 05:35:23,486 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9UiDq1/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:35:35 INFO - 2016-01-09 05:35:35,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 129024000 05:35:35 INFO - 2016-01-09 05:35:35,057 DEBUG : BROWSER_OUTPUT: 05:36:25 INFO - 2016-01-09 05:36:25,756 DEBUG : BROWSER_OUTPUT: 1452346585753 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:38:33 INFO - 2016-01-09 05:38:33,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59838/tests/dromaeo/cssquery-ext.html) 05:38:34 INFO - 2016-01-09 05:38:34,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 219885568 05:38:34 INFO - 2016-01-09 05:38:34,557 DEBUG : BROWSER_OUTPUT: 05:41:36 INFO - 2016-01-09 05:41:36,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/dromaeo/cssquery-ext.html (next: http://localhost:59838/tests/dromaeo/cssquery-jquery.html) 05:41:37 INFO - 2016-01-09 05:41:37,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 207175680 05:41:37 INFO - 2016-01-09 05:41:37,076 DEBUG : BROWSER_OUTPUT: 05:44:38 INFO - 2016-01-09 05:44:38,732 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59838/tests/dromaeo/cssquery-mootools.html) 05:44:39 INFO - 2016-01-09 05:44:39,299 DEBUG : BROWSER_OUTPUT: RSS: Main: 213749760 05:44:39 INFO - 2016-01-09 05:44:39,299 DEBUG : BROWSER_OUTPUT: 05:47:41 INFO - 2016-01-09 05:47:41,047 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59838/tests/dromaeo/cssquery-prototype.html) 05:47:41 INFO - 2016-01-09 05:47:41,609 DEBUG : BROWSER_OUTPUT: RSS: Main: 187310080 05:47:41 INFO - 2016-01-09 05:47:41,609 DEBUG : BROWSER_OUTPUT: 05:50:43 INFO - 2016-01-09 05:50:43,982 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59838/tests/dromaeo/cssquery-yui.html) 05:50:44 INFO - 2016-01-09 05:50:44,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 190590976 05:50:44 INFO - 2016-01-09 05:50:44,535 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,574 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/dromaeo/cssquery-yui.html (next: http://localhost:59838/tests/dromaeo/cssquery-dojo.html) 05:53:46 INFO - 2016-01-09 05:53:46,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 232468480 05:53:46 INFO - 2016-01-09 05:53:46,819 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,820 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:53:46 INFO - 2016-01-09 05:53:46,821 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:53:46 INFO - 2016-01-09 05:53:46,821 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:53:46 INFO - 2016-01-09 05:53:46,821 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:53:46 INFO - 2016-01-09 05:53:46,822 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3278.3443311337733;3351.3967485852536;3381.949270760939;3391.6438773928735;3412.7952322860624;5542.944570554293;5565.805196818113;5567.19275705022;5571.498565129137;5603.215549823028;5583.190437386586;5589.580781441387;5605.075162598177;5611.186377975191;5614.073677843151;4888.926666100011;5073.518015788503;5096.949030509684;5149.459306772772;5202.453742357055;4948.406191257036;5094.923576146361;5128.307678463416;5131.153359695646;5132.692038477703;5003.499650034985;5079.974600126994;5111.284420181178;5118.820841270556;5134.075866344076;5115;5125.538701516844;5125.615578831603;5167.586593072545;5190.92213616796;4833.419989601235;4884.120858245515;4970.105381031413;5095.5159259870625;5101.362329708786;5355.758990845421;5382.811601593965;5391.81128660495;5392.083345831218;5398.2982212312545;5953.196318496991;5953.3748956359395;5959.910601340984;5965.343812180657;5967.8209653710455;5842.532597392198;5860.033094539396;5936.673482958436;5991.4308140727;6002.129691194796;5548.6948217848;5634.690092044936;5643.971780141073;5644.181593668936;5650.0959846424785;6073.848153796191;6092;6096.298925623521;6097.75608975636;6099.542534309944;5744.339400968858;5780.653160810365;5840.941590584125;5869.823905282763;5893.764249430061;5993.910091348634;5997.2803263607775;6006.609570377911;6008.218931538872;6010.008348622516;5728;5968.134620480006;5973;5974.3428222895445;5975.701214939322;5898.085796701518;5978.372270911578;6048;6082.2093127893095;6093.6039157454625;4628.236341003697;4637.118947399983;4638.304254361873;4644.465886423037;4663.953360466419;4156;4165.687573432005;4175;4181.853635122817;4193.391958166048;5517.144842549331;5593.944060559424;5632.493075623213;5709.3434255060365;5709.7716091356715;6035.6076855004285;6037.909431358533;6040.667763272974;6043.576949613484;6070;5481.917771233435;5768.711564421762;5769.682667453247;5775.220345253337;5777.24895763548;5041.395032596112;5122.154844450699;5131.897362052738;5142.717150556755;5194.246834209019;6209.441150296405;6269.216347956505;6273.404026617464;6274.717637706314;6277;5918.526517878646;6030.48740857032;6075.27096748393;6080.665563394055;6082.969585152223;5925.4370834770625;6015;6018.8796224074395;6025.969870150621;6040.818775436744;5839.445252700986;5905.055191169392;5909.320428150818;5915.142304365929;5931.436513531207;5860.2967643883;5892.823215303548;5977.1034344846885;5978.192943952685;5981.282246130492;5867.471927526543;5949.8215053548465;5955.67243801595;5962.70186490665;5963.671998040149;5913.46778789911;5918.970405147947;5922.881542369042;5936.970315148396;5939.227900372924;5503.862403439946;5631.042722737063;5632.211490391266;5754.136879467946;5818.272715910512;5882.470577648032;5888.764449421975;5905;5911.497522710446;5921.585489015728;5764.07774756037;5796.478316951495;5797.8550536236935;5802.6808525531505;5802.767889284381 05:53:46 INFO - 2016-01-09 05:53:46,824 DEBUG : BROWSER_OUTPUT: |1;ext.html;590.3683059126733;625.696537179468;630.3098107572207;632.8797528469589;656.4354654996705;35646.82176589117;44648.77675611622;45315.77342113285;45331.773341133245;45361.319580206284;48554.51445485544;49114;49153.754231228886;49603;49731.50268497314;8178.386621003418;8328.292095171917;9220.492872891988;9362.157405833474;10707.732306692316;10907.945460272727;11046;11048.337099774039;11050.171237157205;11050.171237157245;10980.396078215696;11071.667849964513;11086.16853735969;11087.2238943274;11088.944555277212;10989.505472253728;11036.834447483294;11042.668719938454;11043.061339786127;11099.667009989713;10775.299605525615;10902.182336324808;10907.727306817314;10944.726381840437;10947.890521094767;44589.55410445887;44980.77509612431;45001.54998450007;45051.324230136575;45072.54927450716;3833.195029043904;4006.2988976928923;4281.12236991416;4472.083222939322;4483.775811209459;33233.169170770685;33579.496307555404;33660.326793464235;33991;33995.32009359824;3748.11919198988;3779.111908701453;3808;3813.8474461021524;3904.2581909437117;328138.9999999976;328450;328655;329438;330554;60889.39110608926;61797.69101154465;70782.64608676924;71047.64476177585;71163.64418177876;83464.58267708623;85214;85518.14481855226;85628.57185714031;85643;18017.549561261072;18154.455366339032;18187.727184092248;18208.63582728338;18213.271469141087;13810.171389716448;13842.238676872666;13866.722665546633;13878.167309961233;13881.4447422104;22639.434014149778;22732.31803045911;23037.88481057584;23109.88445057764;23162.884185578965;8594.87107693385;8969.37214394986;8988.820223595621;9010.14403631654;9044.321675874337;997.2697370062936;1043.4313299251926;1089.515165751249;1127.3315493070302;1150.2523359816187;29752.107436776932;29779.255518612208;29882.85058574693;30599.694003059685;30657.84671076675;1110.783397237547;1166.9905531715006;1176.4941075337515;1202.6053146437434;1265.949362025527;15068.321925513379;16213;16405.42581009683;16423;17654.558636033944;472305;477397;479065;480410;480551;63472.04791928125;63755.36244637587;63796.36203637904;64174.67912660407;64181.358186418474;1314.4873499335267;1411.6329754263966;1432.1621851217155;1470.7132109238596;1495.6784291377405;31786;32064.67935320617;32318;33230.667693323725;35748.106297342776;56936;57775.711121444125;57805;57825.71087144706;57959.710201448725;364605;365260;366871;366963;369061;47294.76352618215;52585.474145259584;52606;52827.47172528225;53439.19841202307;49757;50490;50565;50757.746211268706;50928.490715092375;40653.59346406498;42099;42128.3680744783;42593;42760;40914.38628420517;41452.37821432621;41800.79099604482;45144.54855451403;48222.517774821805;43533.78233108814;44413.33379999238;44480.77759611181;45168.32247516356;45261.32108018448;42444;43976;44012.559874400846;44131.33802993022;44646.3303050448;33002.17494562655;33223.16942076467;33269.33461330712;33354;33405.05274847315 05:53:46 INFO - 2016-01-09 05:53:46,828 DEBUG : BROWSER_OUTPUT: |2;jquery.html;379.45169230461977;382.98468061277543;386.3065796894574;386.5709062940134;388.64827331265195;15550.568088094895;18170.63658726826;18212.635747285047;19530.804691953057;19557.902210488945;13402.66493337667;13707.109037912529;13832.23922684252;19266.133024013918;19438.708419373685;4873.074115917973;4985.277134815449;4985.551300382965;4986.825461108862;4991.450940396553;3851.133494963639;4004.2391945530403;4969.726665033421;5266.210068489738;5412.107002344628;5311.362636483627;5337.839864804062;5343.519083282504;5348.465153484659;5364.91952620711;5240.764165612538;5381.865453363658;5433.619646624718;5434.130539113723;5438.456154384569;4684.086603112394;4720.504347043579;4729;4733.077049975256;4734;15401.537953861402;15685.451009214623;15708.371665133382;15715.528534143994;15805.604859878595;2092.204962114387;2150.9139634414605;2182.6289530779645;2219.1678120704737;2281.441046943493;11013.2841365311;11055;11065.778684426266;11146.108311335154;11387.658370248906;1760.3662681434673;1791.973120403195;1830.1215416599973;1872.6254749050245;1918.098493707955;66068;66227.99999999952;67734.32265677379;68161;68413.65793171052;7966.362690984707;8034.15641357649;8092.352611791042;8101.675932962616;8137.349012079019;9825.45959972194;9838.409695418155;9841.45871977048;9847.901520984698;9855.950720246354;6443.001334793114;6445.41991220783;6453.354664533509;6476.255230648536;6508.739650414025;6700;6700.6984685688285;6708.899366509506;6740.831479213059;6753.696083676246;3695.427208782643;3760.5863355030924;3778.1688028633657;3784.0729021389934;3795.2978698940233;1256.7109564800148;1285.2417073926429;1295.4947570447532;1325.648703093681;1331.8867896228928;525.4745254745254;533.714462762428;556.1102236421693;558.7401858135905;578.124141924984;11694.122940779476;13430.462781488826;13678.247696376795;13734.381952812148;13841.377138028813;563.4056070845215;571.865611581278;578.2800413485154;582.1442479555047;606.9605475644069;7217.278272172845;7490.5131166474;7513.624318784039;7622.69509219625;7644.3120119188425;630141;630323;635308;642093;647713;80874;82798;84452;86133.56933215294;86894.56552717196;603.8067818298102;604.9122877182784;609.0225188572366;609.1654433426146;621.2358798677652;12394.256344619353;12428.316442595744;12515.12394132402;12537.373131343214;12545.623631291075;38426.42360364541;38767.612323877525;38945.41581876217;39239.803800980815;39532.011699707735;53375.199372010225;53391;53702.462975369745;54461.72769136129;54613;7798.688052477837;7842.529448232896;7898.565578893216;7900.68397264126;7930.881036784337;10968.561257549609;13669;14110;14153.858461415253;14262.144271343752;10897.237193396386;11674.824877627012;11682.824757628472;11967.521299148284;12001.459934303151;11682.649520514058;11782.941085294518;11844.940775296069;11964.042876570027;12303.200291980993;12202.450889710162;12324.938375308066;12383.566575169825;12528.937355313165;12567;7661.195574464601;7677.347425468674;7709.383249340151;7717.536947783151;7785.14363420012;11782.646520604396;11918.702032448911;12020.879791202326;12190.817137742764;12318 05:53:46 INFO - 2016-01-09 05:53:46,828 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1540.7765873948285;1764.2325588369056;1810.777725035601;1866.8518860900551;1872.3633964452083;7249.093863267091;7322.1213454385415;7785;8105.878411823827;8123.1470695577;7773.378129749621;7976.641051152697;8150.225728555793;8155.021397432316;8163.142869998654;2396.4488167721393;2403.146882856587;2428.429319110008;2428.9514209715794;2430.1980346485616;2117.089651449876;2136.3484137338105;2333.57995560799;2363.444590521226;2383.535210633927;2379.8334116611845;2386.737458879522;2388.522295540899;2388.79695225906;2389.82076344274;2356.6582845487396;2370.5021945391404;2371.2174982255815;2373.311739595515;2374.584447721642;2358.481134150484;2422.3338581889943;2424.3939015246187;2424.648425978241;2425.4300239443714;7756.418268629819;7798.103218129931;7802.33680137189;7803.648835802403;7806.687732490694;5118.385793704742;5187.144121219994;5190.351206099238;5193.324867767166;5197.558207552363;3202.519622056687;3231.88688395905;3299.1257316811066;3313.287643156733;3313.5029745538122;1072.7050061233144;1079.0666073846146;1082.1396989393372;1082.6309414934344;1092.4264761000459;10842.241043126984;10843.186760992958;10846.837297440545;10918.781624367548;10957.780844383147;4555.202839503074;4603.286490593963;4609.285560738091;4736.313234580966;4739.123262196505;9394.201492873182;9404.811903762024;9405.153536181777;9405.153536181777;9427.198688111588;5171.50870667286;5188.714620695822;5197.636165468381;5198.454162312978;5200.089984252741;4730.030343779517;4746.7389293589185;4751.168545504592;4751.3348131261655;4754.7147171169845;3604.4232922732235;3960.881173564798;3978.144698889753;3986.5614782373914;4068.8372465101656;1105.160078340467;1220.4263995921901;1230.163488827588;1236.208826351118;1295.6048405236493;1244.4089057697522;1300.7138429545485;1462.144645382442;1464.6777708904024;1469.4709904434394;7920.089189743138;7991.800204994922;8006.919930800734;8012.919870801334;8013.559254240956;1194.5699548162654;1201.9699507512207;1220.4995951659955;1238.38699843578;1239.5227837282712;6350.206224221973;6392.520560957947;6433.131527243856;6438.6136831790245;6440.033994900802;9740.17208537268;9880.555375008142;9883.406995580288;9888.258380621339;9901;9627.085426884434;9789.21686265097;9803.166730827817;9818.75453113678;9903.752406189758;7669.271419215165;7737.497062690907;7741.148473667892;7744;7748.22517748232;7393.112826460859;7393.112826460859;7394.260573942563;7414.62926853666;7599.848003040019;8634.5251011195;8757.956210218908;8837.469751814655;8851.734447966572;8856.114388561093;8776.122387761428;8991.05593912656;9017.323700722603;9018.09819018093;9054.456732596065;7445.4415918805225;7495.063117110361;7505.924940750741;7507.174210836913;7510.061242344707;6943.27095654949;7064.046353742179;7885.763427097197;8072.152423995631;8072.515649061075;7287.890681639886;7344.853102938018;7471.551706897603;7504.962475187589;7694.576798276148;7140.714371425085;7166.713331466683;7418.146913105062;7559.584222867795;7564.319211271012;7221.45839062083;7638.274363935418;7683;7687.192844751221;7710.190430004769;7052.753153639598;7104.893426598502;7105.32499412555;7107.644617768988;7117.679704413417;7038.401735852305;7077.292270772881;7123.750668726777;7129.180144283267;7132.108486439195 05:53:46 INFO - 2016-01-09 05:53:46,831 DEBUG : BROWSER_OUTPUT: |4;prototype.html;363.9090227443139;365.66176286934586;367.99816000920026;371.5726914048843;373.78507358268985;5824.388439213885;5876.823695289143;5915.792947246847;5922.407759224076;5942.5840191186635;5759.971200143993;5766;5807.796727114542;5811.680357580342;5832.533397328214;4635.721856688592;4649.349091127245;4650.232711602582;4659.487456379812;4662.160811054009;4514.954850451502;4560.657950653697;4569.200389931765;4627.3521706961055;4664;4552.9089418211615;4579.427571553556;4615.353850460938;4616.49218585957;4616.6306695464455;4588.220002599566;4592.701474404186;4595.517470665583;4597.54024597541;4600.516945720685;4096.672266218696;4100;4100.815463304129;4104.42538044674;4107.199096176247;5536.114221724545;5597.468240517144;5664.235328230678;5679.971600141973;5680.6591604503865;5405.567554595623;5410.594205434608;5413.404525502192;5414.539764120054;5418.349798024262;6283.277423096311;6288.088227207029;6291.5281353898645;6295.370462953714;6302.55882088254;4400.889977750549;4454.2873140297725;4506.301523263899;4533.138703646329;4546.636269098464;6310.558260921737;6319.968400158015;6329.556931014785;6353.459955903753;6393.8401539961405;4630.236011058205;4634.026854360554;4640.071985602893;4641.327007583882;4648.465426475931;4619.376384188159;4695.460022097503;4702.31816386622;4703;4741.2651039089;5361.839144825662;5396.244525766396;5396.379416367089;5400.486953739389;5402.135658294654;6285.434310911948;6321.431071203614;6331.461825744863;6333;6341.175647165839;1718.3985605038138;1720.7849018872641;1725.2495164603438;1725.284007137024;1725.7583938248656;1142.4344949250174;1157.253571446429;1161.848959635259;1168.1589255735857;1187.524990003988;6170.475509581735;6175.44421002112;6176.413240742122;6180.165677633552;6181.474574661204;6432.35676432353;6465.806025819139;6483.448906842877;6483.740650374027;6487.124238227779;6030.939690603125;6146.661933593696;6158.630482171084;6160.907586386208;6162.476189523851;3536.80547569881;3564.3049605326714;3566.5006899034156;3567.8751243706347;3569.1077230692326;6483.7730679425995;6558;6562.212534495891;6564.540482166298;6575.572587781779;6573.1783527059115;6583.80248592543;6586.769463068866;6587.571807832475;6590.472762179014;6535.607863528204;6641.50188735837;6656.96671516649;6658.1011563438315;6666.766663166766;6258.217722784652;6395.872082558416;6405.679716014274;6433.903491447539;6449.516286278454;6265.060240963892;6289.874202516015;6352.745890164341;6353.491720662429;6360.363963603602;6636.601803891782;6672.633005184762;6677.232118306457;6681.899771503334;6705.798826035227;4523.434570678665;4543.273076307775;4547.40883685132;4548.47692515365;4549.545045495424;6274.874502509833;6386.457151142205;6416.935830641821;6444.838879027874;6549.770758023446;6321.11504389395;6354.26925903527;6354.6504942226775;6383.457406120532;6386.680665966776;6345.048242763438;6353.205849268841;6361.045843123568;6373.075903993988;6389.744410223725;6135.969320153549;6219.968900155471;6227.688615569294;6229.12792209082;6232.906506402317;6347.492200624031;6355.555111142361;6356.5232607555545;6361.586496877688;6364.07720880479;6101.267847858286;6191.25704915413;6199.442050215503;6202.937970620416;6210.658413787104 05:53:46 INFO - 2016-01-09 05:53:46,833 DEBUG : BROWSER_OUTPUT: |5;yui.html;915.1854849184227;934.2712684106399;942.1473566422387;944.5607792376544;944.8629948657444;13997.090189137713;14195.503157389468;14206.928965355159;14227.85772142277;14276.357563909623;12692.238465692042;12781.169224000456;12786.16889902158;12793.872061279384;12811.551595694153;763.6868883757661;777.2965466253022;907.419251678926;907.5462268865583;914.1818072824818;920.6593560382668;920.6915683246087;920.7467946314783;927.1331305229631;927.3091546797611;973.0221127766584;976.8827740671094;979.2019504104168;982.5676702250987;982.8132654795602;800.3277247112425;812.5327936436543;960.4429430930079;973.7176218896511;975.3855071305103;719.1909102259957;722.288545782404;722.4220623501178;722.5664601239267;723.2442098007542;13561.25413102279;13582.049256552045;13644.181349119084;13736.381862816197;13755.31223438834;12053.457594408274;12524.373781310898;12584.74830503394;12642.620721378373;12716.745665086739;9659.565319560566;9802.117809397188;9853.261005424622;10000.099991000845;10004;3416.231347946717;3438.9828050859587;3445.8277086145595;3477.6522347765276;3535.982320088409;54274.72862635701;54425.455745442436;54483.72758136223;56574.717126414514;56725.71637141788;10193.541290641939;10204.795904081877;10230.386176829265;10317.587296508205;10371.636992705218;17289.82710172907;17329.2201850917;17393.739093913602;17435.128243588024;17437.7384339235;8105.6757729691335;8128;8262.091169971298;8267.255946964802;8301.958490207631;4469.21788686985;4545.045540436479;4579.473360563577;4591.8392856249875;4595.287730401793;3293.275479394529;3330.0842268375904;3335.24956884699;3337.749668774803;3340.0313908966614;498.2277469921607;510.18879980829917;511.50639632753825;512.1575009109994;522.4618642797924;498.16059939002895;674.3389247269148;678.4097834883585;760.9885851712229;763.5838468034934;10020.799584008279;10211.795764084825;10215.489225538693;10259.333143345659;10358.171346292278;657.5988646925372;700.6846918886522;732.1031736123313;752.6349720385608;759.8214419611387;4453.844115456009;4456.398386217884;4477.776111194493;4529.569690879362;4535.659825513099;70797.2920270801;71928.64035679789;72187;72323.63838180776;72341.63829180821;61129;61211.38788612057;61489.38510614926;61576.38423615796;62280.06579901305;55216.723916380164;55220.72389638026;55319;55446.72276638591;56830.43169568334;8824.470531768115;9012.684556040507;9064.093590640883;9158.129977652112;9173.724788256362;40308.39537406882;40309.39535906905;40449.595504044584;40679.1864162721;41341.173176536904;46709.31356430431;52279.477205227464;52691.47308527019;52981.47018529765;53207;8138.552379618941;8190.385721070825;8291.253787159185;8323.542205178774;8451.112633173674;12090.274583525017;12130.821025303;12294.262344259372;12405.875941240472;12555.874441255835;10791.35251884858;10813.62152324665;10854.891451085388;10863.619773307897;11091.389973551532;11665.708357291136;11723.941380293043;11768.176227663982;11819.40902954866;11887.227330223508;10660.946695266784;11203.495842686936;11353.772924541629;11705.180637355303;12070.27578345302;10944.01503864656;11001.724956876142;11044.779104418027;11140.220184587;11285.605003824827;10205.489725513844;10361.274710770174;10363.740906477398;10407.531661075422;10442.321249118782 05:53:46 INFO - 2016-01-09 05:53:46,833 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:53:46 INFO - 2016-01-09 05:53:46,833 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:53:46 INFO - 2016-01-09 05:53:46,833 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,730 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: __startTimestamp1452347626819__endTimestamp 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,834 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5556.99 Median:5780.65 stddev:609.66 (10.5%) stddev-sans-first:584.74 05:53:46 INFO - 2016-01-09 05:53:46,835 DEBUG : BROWSER_OUTPUT: Values: 3278.3 3351.4 3381.9 3391.6 3412.8 5542.9 5565.8 5567.2 5571.5 5603.2 5583.2 5589.6 5605.1 5611.2 5614.1 4888.9 5073.5 5096.9 5149.5 5202.5 4948.4 5094.9 5128.3 5131.2 5132.7 5003.5 5080.0 5111.3 5118.8 5134.1 5115.0 5125.5 5125.6 5167.6 5190.9 4833.4 4884.1 4970.1 5095.5 5101.4 5355.8 5382.8 5391.8 5392.1 5398.3 5953.2 5953.4 5959.9 5965.3 5967.8 5842.5 5860.0 5936.7 5991.4 6002.1 5548.7 5634.7 5644.0 5644.2 5650.1 6073.8 6092.0 6096.3 6097.8 6099.5 5744.3 5780.7 5840.9 5869.8 5893.8 5993.9 5997.3 6006.6 6008.2 6010.0 5728.0 5968.1 5973.0 5974.3 5975.7 5898.1 5978.4 6048.0 6082.2 6093.6 4628.2 4637.1 4638.3 4644.5 4664.0 4156.0 4165.7 4175.0 4181.9 4193.4 5517.1 5593.9 5632.5 5709.3 5709.8 6035.6 6037.9 6040.7 6043.6 6070.0 5481.9 5768.7 5769.7 5775.2 5777.2 5041.4 5122.2 5131.9 5142.7 5194.2 6209.4 6269.2 6273.4 6274.7 6277.0 5918.5 6030.5 6075.3 6080.7 6083.0 5925.4 6015.0 6018.9 6026.0 6040.8 5839.4 5905.1 5909.3 5915.1 5931.4 5860.3 5892.8 5977.1 5978.2 5981.3 5867.5 5949.8 5955.7 5962.7 5963.7 5913.5 5919.0 5922.9 5937.0 5939.2 5503.9 5631.0 5632.2 5754.1 5818.3 5882.5 5888.8 5905.0 5911.5 5921.6 5764.1 5796.5 5797.9 5802.7 5802.8 05:53:46 INFO - 2016-01-09 05:53:46,835 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,836 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:59355.95 Median:3868.73 stddev:104213.82 (2693.8%) stddev-sans-first:104412.77 05:53:46 INFO - 2016-01-09 05:53:46,836 DEBUG : BROWSER_OUTPUT: Values: 590.4 625.7 630.3 632.9 656.4 35646.8 44648.8 45315.8 45331.8 45361.3 48554.5 49114.0 49153.8 49603.0 49731.5 8178.4 8328.3 9220.5 9362.2 10707.7 10907.9 11046.0 11048.3 11050.2 11050.2 10980.4 11071.7 11086.2 11087.2 11088.9 10989.5 11036.8 11042.7 11043.1 11099.7 10775.3 10902.2 10907.7 10944.7 10947.9 44589.6 44980.8 45001.5 45051.3 45072.5 3833.2 4006.3 4281.1 4472.1 4483.8 33233.2 33579.5 33660.3 33991.0 33995.3 3748.1 3779.1 3808.0 3813.8 3904.3 328139.0 328450.0 328655.0 329438.0 330554.0 60889.4 61797.7 70782.6 71047.6 71163.6 83464.6 85214.0 85518.1 85628.6 85643.0 18017.5 18154.5 18187.7 18208.6 18213.3 13810.2 13842.2 13866.7 13878.2 13881.4 22639.4 22732.3 23037.9 23109.9 23162.9 8594.9 8969.4 8988.8 9010.1 9044.3 997.3 1043.4 1089.5 1127.3 1150.3 29752.1 29779.3 29882.9 30599.7 30657.8 1110.8 1167.0 1176.5 1202.6 1265.9 15068.3 16213.0 16405.4 16423.0 17654.6 472305.0 477397.0 479065.0 480410.0 480551.0 63472.0 63755.4 63796.4 64174.7 64181.4 1314.5 1411.6 1432.2 1470.7 1495.7 31786.0 32064.7 32318.0 33230.7 35748.1 56936.0 57775.7 57805.0 57825.7 57959.7 364605.0 365260.0 366871.0 366963.0 369061.0 47294.8 52585.5 52606.0 52827.5 53439.2 49757.0 50490.0 50565.0 50757.7 50928.5 40653.6 42099.0 42128.4 42593.0 42760.0 40914.4 41452.4 41800.8 45144.5 48222.5 43533.8 44413.3 44480.8 45168.3 45261.3 42444.0 43976.0 44012.6 44131.3 44646.3 33002.2 33223.2 33269.3 33354.0 33405.1 05:53:46 INFO - 2016-01-09 05:53:46,836 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,837 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31116.25 Median:4733.54 stddev:104346.98 (2204.4%) stddev-sans-first:104614.18 05:53:46 INFO - 2016-01-09 05:53:46,838 DEBUG : BROWSER_OUTPUT: Values: 379.5 383.0 386.3 386.6 388.6 15550.6 18170.6 18212.6 19530.8 19557.9 13402.7 13707.1 13832.2 19266.1 19438.7 4873.1 4985.3 4985.6 4986.8 4991.5 3851.1 4004.2 4969.7 5266.2 5412.1 5311.4 5337.8 5343.5 5348.5 5364.9 5240.8 5381.9 5433.6 5434.1 5438.5 4684.1 4720.5 4729.0 4733.1 4734.0 15401.5 15685.5 15708.4 15715.5 15805.6 2092.2 2150.9 2182.6 2219.2 2281.4 11013.3 11055.0 11065.8 11146.1 11387.7 1760.4 1792.0 1830.1 1872.6 1918.1 66068.0 66228.0 67734.3 68161.0 68413.7 7966.4 8034.2 8092.4 8101.7 8137.3 9825.5 9838.4 9841.5 9847.9 9856.0 6443.0 6445.4 6453.4 6476.3 6508.7 6700.0 6700.7 6708.9 6740.8 6753.7 3695.4 3760.6 3778.2 3784.1 3795.3 1256.7 1285.2 1295.5 1325.6 1331.9 525.5 533.7 556.1 558.7 578.1 11694.1 13430.5 13678.2 13734.4 13841.4 563.4 571.9 578.3 582.1 607.0 7217.3 7490.5 7513.6 7622.7 7644.3 630141.0 630323.0 635308.0 642093.0 647713.0 80874.0 82798.0 84452.0 86133.6 86894.6 603.8 604.9 609.0 609.2 621.2 12394.3 12428.3 12515.1 12537.4 12545.6 38426.4 38767.6 38945.4 39239.8 39532.0 53375.2 53391.0 53702.5 54461.7 54613.0 7798.7 7842.5 7898.6 7900.7 7930.9 10968.6 13669.0 14110.0 14153.9 14262.1 10897.2 11674.8 11682.8 11967.5 12001.5 11682.6 11782.9 11844.9 11964.0 12303.2 12202.5 12324.9 12383.6 12528.9 12567.0 7661.2 7677.3 7709.4 7717.5 7785.1 11782.6 11918.7 12020.9 12190.8 12318.0 05:53:46 INFO - 2016-01-09 05:53:46,838 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,838 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5778.27 Median:6990.84 stddev:2918.23 (41.7%) stddev-sans-first:2909.03 05:53:46 INFO - 2016-01-09 05:53:46,840 DEBUG : BROWSER_OUTPUT: Values: 1540.8 1764.2 1810.8 1866.9 1872.4 7249.1 7322.1 7785.0 8105.9 8123.1 7773.4 7976.6 8150.2 8155.0 8163.1 2396.4 2403.1 2428.4 2429.0 2430.2 2117.1 2136.3 2333.6 2363.4 2383.5 2379.8 2386.7 2388.5 2388.8 2389.8 2356.7 2370.5 2371.2 2373.3 2374.6 2358.5 2422.3 2424.4 2424.6 2425.4 7756.4 7798.1 7802.3 7803.6 7806.7 5118.4 5187.1 5190.4 5193.3 5197.6 3202.5 3231.9 3299.1 3313.3 3313.5 1072.7 1079.1 1082.1 1082.6 1092.4 10842.2 10843.2 10846.8 10918.8 10957.8 4555.2 4603.3 4609.3 4736.3 4739.1 9394.2 9404.8 9405.2 9405.2 9427.2 5171.5 5188.7 5197.6 5198.5 5200.1 4730.0 4746.7 4751.2 4751.3 4754.7 3604.4 3960.9 3978.1 3986.6 4068.8 1105.2 1220.4 1230.2 1236.2 1295.6 1244.4 1300.7 1462.1 1464.7 1469.5 7920.1 7991.8 8006.9 8012.9 8013.6 1194.6 1202.0 1220.5 1238.4 1239.5 6350.2 6392.5 6433.1 6438.6 6440.0 9740.2 9880.6 9883.4 9888.3 9901.0 9627.1 9789.2 9803.2 9818.8 9903.8 7669.3 7737.5 7741.1 7744.0 7748.2 7393.1 7393.1 7394.3 7414.6 7599.8 8634.5 8758.0 8837.5 8851.7 8856.1 8776.1 8991.1 9017.3 9018.1 9054.5 7445.4 7495.1 7505.9 7507.2 7510.1 6943.3 7064.0 7885.8 8072.2 8072.5 7287.9 7344.9 7471.6 7505.0 7694.6 7140.7 7166.7 7418.1 7559.6 7564.3 7221.5 7638.3 7683.0 7687.2 7710.2 7052.8 7104.9 7105.3 7107.6 7117.7 7038.4 7077.3 7123.8 7129.2 7132.1 05:53:46 INFO - 2016-01-09 05:53:46,840 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,840 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5294.76 Median:6066.10 stddev:1538.05 (25.4%) stddev-sans-first:1497.18 05:53:46 INFO - 2016-01-09 05:53:46,841 DEBUG : BROWSER_OUTPUT: Values: 363.9 365.7 368.0 371.6 373.8 5824.4 5876.8 5915.8 5922.4 5942.6 5760.0 5766.0 5807.8 5811.7 5832.5 4635.7 4649.3 4650.2 4659.5 4662.2 4515.0 4560.7 4569.2 4627.4 4664.0 4552.9 4579.4 4615.4 4616.5 4616.6 4588.2 4592.7 4595.5 4597.5 4600.5 4096.7 4100.0 4100.8 4104.4 4107.2 5536.1 5597.5 5664.2 5680.0 5680.7 5405.6 5410.6 5413.4 5414.5 5418.3 6283.3 6288.1 6291.5 6295.4 6302.6 4400.9 4454.3 4506.3 4533.1 4546.6 6310.6 6320.0 6329.6 6353.5 6393.8 4630.2 4634.0 4640.1 4641.3 4648.5 4619.4 4695.5 4702.3 4703.0 4741.3 5361.8 5396.2 5396.4 5400.5 5402.1 6285.4 6321.4 6331.5 6333.0 6341.2 1718.4 1720.8 1725.2 1725.3 1725.8 1142.4 1157.3 1161.8 1168.2 1187.5 6170.5 6175.4 6176.4 6180.2 6181.5 6432.4 6465.8 6483.4 6483.7 6487.1 6030.9 6146.7 6158.6 6160.9 6162.5 3536.8 3564.3 3566.5 3567.9 3569.1 6483.8 6558.0 6562.2 6564.5 6575.6 6573.2 6583.8 6586.8 6587.6 6590.5 6535.6 6641.5 6657.0 6658.1 6666.8 6258.2 6395.9 6405.7 6433.9 6449.5 6265.1 6289.9 6352.7 6353.5 6360.4 6636.6 6672.6 6677.2 6681.9 6705.8 4523.4 4543.3 4547.4 4548.5 4549.5 6274.9 6386.5 6416.9 6444.8 6549.8 6321.1 6354.3 6354.7 6383.5 6386.7 6345.0 6353.2 6361.0 6373.1 6389.7 6136.0 6220.0 6227.7 6229.1 6232.9 6347.5 6355.6 6356.5 6361.6 6364.1 6101.3 6191.3 6199.4 6202.9 6210.7 05:53:46 INFO - 2016-01-09 05:53:46,841 DEBUG : BROWSER_OUTPUT: 05:53:46 INFO - 2016-01-09 05:53:46,842 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15382.49 Median:4593.56 stddev:19260.49 (419.3%) stddev-sans-first:19283.88 05:53:46 INFO - 2016-01-09 05:53:46,843 DEBUG : BROWSER_OUTPUT: Values: 915.2 934.3 942.1 944.6 944.9 13997.1 14195.5 14206.9 14227.9 14276.4 12692.2 12781.2 12786.2 12793.9 12811.6 763.7 777.3 907.4 907.5 914.2 920.7 920.7 920.7 927.1 927.3 973.0 976.9 979.2 982.6 982.8 800.3 812.5 960.4 973.7 975.4 719.2 722.3 722.4 722.6 723.2 13561.3 13582.0 13644.2 13736.4 13755.3 12053.5 12524.4 12584.7 12642.6 12716.7 9659.6 9802.1 9853.3 10000.1 10004.0 3416.2 3439.0 3445.8 3477.7 3536.0 54274.7 54425.5 54483.7 56574.7 56725.7 10193.5 10204.8 10230.4 10317.6 10371.6 17289.8 17329.2 17393.7 17435.1 17437.7 8105.7 8128.0 8262.1 8267.3 8302.0 4469.2 4545.0 4579.5 4591.8 4595.3 3293.3 3330.1 3335.2 3337.7 3340.0 498.2 510.2 511.5 512.2 522.5 498.2 674.3 678.4 761.0 763.6 10020.8 10211.8 10215.5 10259.3 10358.2 657.6 700.7 732.1 752.6 759.8 4453.8 4456.4 4477.8 4529.6 4535.7 70797.3 71928.6 72187.0 72323.6 72341.6 61129.0 61211.4 61489.4 61576.4 62280.1 55216.7 55220.7 55319.0 55446.7 56830.4 8824.5 9012.7 9064.1 9158.1 9173.7 40308.4 40309.4 40449.6 40679.2 41341.2 46709.3 52279.5 52691.5 52981.5 53207.0 8138.6 8190.4 8291.3 8323.5 8451.1 12090.3 12130.8 12294.3 12405.9 12555.9 10791.4 10813.6 10854.9 10863.6 11091.4 11665.7 11723.9 11768.2 11819.4 11887.2 10660.9 11203.5 11353.8 11705.2 12070.3 10944.0 11001.7 11044.8 11140.2 11285.6 10205.5 10361.3 10363.7 10407.5 10442.3 05:53:46 INFO - 2016-01-09 05:53:46,843 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:53:46 INFO - 2016-01-09 05:53:46,843 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2016-01-09 05:53:47,425 INFO : Browser exited with error code: 0 05:53:47 INFO - 2016-01-09 05:53:47,445 INFO : Completed test dromaeo_css (00:18:28) 05:53:47 INFO - 2016-01-09 05:53:47,446 INFO : Starting test kraken 05:53:47 INFO - 2016-01-09 05:53:47,446 DEBUG : operating with platform_type : linux_ 05:53:47 INFO - 2016-01-09 05:53:47,446 INFO : Initialising browser for kraken test... 05:53:47 INFO - 2016-01-09 05:53:47,452 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6kUDgu/profile http://localhost:59838/getInfo.html 05:53:50 INFO - 2016-01-09 05:53:50,915 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:53:50 INFO - 2016-01-09 05:53:50,915 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:53:50 INFO - 2016-01-09 05:53:50,916 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:53:50 INFO - 2016-01-09 05:53:50,916 DEBUG : BROWSER_OUTPUT: __metrics 05:53:51 INFO - 2016-01-09 05:53:51,064 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:53:52 INFO - 2016-01-09 05:53:52,085 INFO : Browser initialized. 05:53:52 INFO - 2016-01-09 05:53:52,086 INFO : Running cycle 1/1 for kraken test... 05:53:52 INFO - 2016-01-09 05:53:52,086 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6kUDgu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:54:03 INFO - 2016-01-09 05:54:03,618 DEBUG : BROWSER_OUTPUT: RSS: Main: 128344064 05:54:03 INFO - 2016-01-09 05:54:03,618 DEBUG : BROWSER_OUTPUT: 05:54:06 INFO - 2016-01-09 05:54:06,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59838/tests/kraken/driver.html?testName=audio-beat-detection) 05:54:06 INFO - 2016-01-09 05:54:06,649 DEBUG : BROWSER_OUTPUT: RSS: Main: 163790848 05:54:06 INFO - 2016-01-09 05:54:06,649 DEBUG : BROWSER_OUTPUT: 05:54:09 INFO - 2016-01-09 05:54:09,178 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59838/tests/kraken/driver.html?testName=audio-dft) 05:54:09 INFO - 2016-01-09 05:54:09,747 DEBUG : BROWSER_OUTPUT: RSS: Main: 159764480 05:54:09 INFO - 2016-01-09 05:54:09,747 DEBUG : BROWSER_OUTPUT: 05:54:12 INFO - 2016-01-09 05:54:12,525 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59838/tests/kraken/driver.html?testName=audio-fft) 05:54:13 INFO - 2016-01-09 05:54:13,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 157405184 05:54:13 INFO - 2016-01-09 05:54:13,087 DEBUG : BROWSER_OUTPUT: 05:54:15 INFO - 2016-01-09 05:54:15,051 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59838/tests/kraken/driver.html?testName=audio-oscillator) 05:54:15 INFO - 2016-01-09 05:54:15,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 157585408 05:54:15 INFO - 2016-01-09 05:54:15,613 DEBUG : BROWSER_OUTPUT: 05:54:17 INFO - 2016-01-09 05:54:17,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59838/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:54:18 INFO - 2016-01-09 05:54:18,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 156532736 05:54:18 INFO - 2016-01-09 05:54:18,288 DEBUG : BROWSER_OUTPUT: 05:54:21 INFO - 2016-01-09 05:54:21,166 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59838/tests/kraken/driver.html?testName=imaging-darkroom) 05:54:21 INFO - 2016-01-09 05:54:21,734 DEBUG : BROWSER_OUTPUT: RSS: Main: 155250688 05:54:21 INFO - 2016-01-09 05:54:21,735 DEBUG : BROWSER_OUTPUT: 05:54:25 INFO - 2016-01-09 05:54:25,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59838/tests/kraken/driver.html?testName=imaging-desaturate) 05:54:25 INFO - 2016-01-09 05:54:25,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 158515200 05:54:25 INFO - 2016-01-09 05:54:25,643 DEBUG : BROWSER_OUTPUT: 05:54:28 INFO - 2016-01-09 05:54:28,383 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59838/tests/kraken/driver.html?testName=json-parse-financial) 05:54:28 INFO - 2016-01-09 05:54:28,949 DEBUG : BROWSER_OUTPUT: RSS: Main: 156590080 05:54:28 INFO - 2016-01-09 05:54:28,949 DEBUG : BROWSER_OUTPUT: 05:54:30 INFO - 2016-01-09 05:54:30,461 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59838/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:54:31 INFO - 2016-01-09 05:54:31,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 160423936 05:54:31 INFO - 2016-01-09 05:54:31,024 DEBUG : BROWSER_OUTPUT: 05:54:32 INFO - 2016-01-09 05:54:32,761 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:54:33 INFO - 2016-01-09 05:54:33,363 DEBUG : BROWSER_OUTPUT: RSS: Main: 161529856 05:54:33 INFO - 2016-01-09 05:54:33,363 DEBUG : BROWSER_OUTPUT: 05:54:35 INFO - 2016-01-09 05:54:35,340 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:54:35 INFO - 2016-01-09 05:54:35,906 DEBUG : BROWSER_OUTPUT: RSS: Main: 165924864 05:54:35 INFO - 2016-01-09 05:54:35,907 DEBUG : BROWSER_OUTPUT: 05:54:38 INFO - 2016-01-09 05:54:38,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:54:38 INFO - 2016-01-09 05:54:38,795 DEBUG : BROWSER_OUTPUT: RSS: Main: 169082880 05:54:38 INFO - 2016-01-09 05:54:38,795 DEBUG : BROWSER_OUTPUT: 05:54:41 INFO - 2016-01-09 05:54:41,209 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:54:41 INFO - 2016-01-09 05:54:41,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 168370176 05:54:41 INFO - 2016-01-09 05:54:41,775 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59838/tests/kraken/driver.html?testName=ai-astar) 05:54:43 INFO - 2016-01-09 05:54:43,638 DEBUG : BROWSER_OUTPUT: RSS: Main: 226406400 05:54:43 INFO - 2016-01-09 05:54:43,638 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,642 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:54:43 INFO - 2016-01-09 05:54:43,642 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:54:43 INFO - 2016-01-09 05:54:43,642 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:54:43 INFO - 2016-01-09 05:54:43,642 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:54:43 INFO - 2016-01-09 05:54:43,643 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;104;109;110;111;100;109;99;109 05:54:43 INFO - 2016-01-09 05:54:43,643 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;124;147;162;129;148;146;122;156;123 05:54:43 INFO - 2016-01-09 05:54:43,643 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;160;162;159;159;161;159;160 05:54:43 INFO - 2016-01-09 05:54:43,643 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;71;153;71;71;71;71;152;71 05:54:43 INFO - 2016-01-09 05:54:43,643 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;131;90;90;89;129;89;89;140 05:54:43 INFO - 2016-01-09 05:54:43,644 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;110;109;109;109;109;110 05:54:43 INFO - 2016-01-09 05:54:43,644 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;145;146;146;147;144;144;145;145 05:54:43 INFO - 2016-01-09 05:54:43,644 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;96;95;95;95;97;96 05:54:43 INFO - 2016-01-09 05:54:43,644 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;55;54;55;61;55;63;54;53 05:54:43 INFO - 2016-01-09 05:54:43,645 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;62;63;62;63;63;62;63;69;63 05:54:43 INFO - 2016-01-09 05:54:43,645 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;76;77;85;85;78;78;77;85;77 05:54:43 INFO - 2016-01-09 05:54:43,645 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;108;109;108;105;114;117;119;113;116;117 05:54:43 INFO - 2016-01-09 05:54:43,645 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;136;139;136;136;135;138;138;134;137;150 05:54:43 INFO - 2016-01-09 05:54:43,645 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;61;63;61;64;65;61;62;60;60;60 05:54:43 INFO - 2016-01-09 05:54:43,646 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:54:43 INFO - 2016-01-09 05:54:43,646 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:54:43 INFO - 2016-01-09 05:54:43,646 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,879 05:54:43 INFO - 2016-01-09 05:54:43,646 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:54:43 INFO - 2016-01-09 05:54:43,647 DEBUG : BROWSER_OUTPUT: __startTimestamp1452347683640__endTimestamp 05:54:43 INFO - 2016-01-09 05:54:43,647 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,650 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:54:43 INFO - 2016-01-09 05:54:43,650 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:54:43 INFO - 2016-01-09 05:54:43,650 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.70 Median:109.00 stddev:4.24 (3.9%) stddev-sans-first:4.50 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 104.0 109.0 110.0 111.0 100.0 109.0 99.0 109.0 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.70 Median:147.50 stddev:14.81 (10.0%) stddev-sans-first:15.32 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: Values: 150.0 124.0 147.0 162.0 129.0 148.0 146.0 122.0 156.0 123.0 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 160.0 162.0 159.0 159.0 161.0 159.0 160.0 05:54:43 INFO - 2016-01-09 05:54:43,651 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.20 Median:71.00 stddev:34.42 (48.5%) stddev-sans-first:35.94 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 153.0 71.0 71.0 71.0 71.0 152.0 71.0 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.50 Median:89.00 stddev:21.46 (24.1%) stddev-sans-first:22.20 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 131.0 90.0 90.0 89.0 129.0 89.0 89.0 140.0 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:54:43 INFO - 2016-01-09 05:54:43,652 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 110.0 05:54:43 INFO - 2016-01-09 05:54:43,653 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,653 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.50 stddev:0.95 (0.7%) stddev-sans-first:0.97 05:54:43 INFO - 2016-01-09 05:54:43,653 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 145.0 146.0 146.0 147.0 144.0 144.0 145.0 145.0 05:54:43 INFO - 2016-01-09 05:54:43,653 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,653 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 05:54:43 INFO - 2016-01-09 05:54:43,653 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 97.0 96.0 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.10 Median:55.00 stddev:3.25 (5.9%) stddev-sans-first:3.42 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 55.0 54.0 55.0 61.0 55.0 63.0 54.0 53.0 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:2.06 (3.3%) stddev-sans-first:2.18 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 63.0 62.0 63.0 63.0 62.0 63.0 69.0 63.0 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:78.00 stddev:3.84 (4.9%) stddev-sans-first:3.96 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 77.0 85.0 85.0 78.0 78.0 77.0 85.0 77.0 05:54:43 INFO - 2016-01-09 05:54:43,654 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.60 Median:115.00 stddev:4.79 (4.2%) stddev-sans-first:4.78 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 108.0 105.0 114.0 117.0 119.0 113.0 116.0 117.0 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.90 Median:137.50 stddev:4.51 (3.3%) stddev-sans-first:4.73 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: Values: 136.0 139.0 136.0 136.0 135.0 138.0 138.0 134.0 137.0 150.0 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.50 stddev:1.77 (2.9%) stddev-sans-first:1.86 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: Values: 61.0 63.0 61.0 64.0 65.0 61.0 62.0 60.0 60.0 60.0 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:54:43 INFO - 2016-01-09 05:54:43,655 DEBUG : BROWSER_OUTPUT: 05:54:44 INFO - 2016-01-09 05:54:44,285 INFO : Browser exited with error code: 0 05:54:44 INFO - 2016-01-09 05:54:44,335 INFO : Completed test kraken (00:00:56) 05:54:44 INFO - 2016-01-09 05:54:44,335 INFO : Starting test v8_7 05:54:44 INFO - 2016-01-09 05:54:44,335 DEBUG : operating with platform_type : linux_ 05:54:44 INFO - 2016-01-09 05:54:44,335 INFO : Initialising browser for v8_7 test... 05:54:44 INFO - 2016-01-09 05:54:44,342 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpA8_4oj/profile http://localhost:59838/getInfo.html 05:54:47 INFO - 2016-01-09 05:54:47,671 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:54:47 INFO - 2016-01-09 05:54:47,672 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:54:47 INFO - 2016-01-09 05:54:47,672 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:54:47 INFO - 2016-01-09 05:54:47,672 DEBUG : BROWSER_OUTPUT: __metrics 05:54:47 INFO - 2016-01-09 05:54:47,794 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:54:48 INFO - 2016-01-09 05:54:48,889 INFO : Browser initialized. 05:54:48 INFO - 2016-01-09 05:54:48,889 INFO : Running cycle 1/1 for v8_7 test... 05:54:48 INFO - 2016-01-09 05:54:48,890 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpA8_4oj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:55:00 INFO - 2016-01-09 05:55:00,435 DEBUG : BROWSER_OUTPUT: RSS: Main: 128380928 05:55:00 INFO - 2016-01-09 05:55:00,436 DEBUG : BROWSER_OUTPUT: 05:55:21 INFO - 2016-01-09 05:55:21,835 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59838/tests/v8_7/run.html (next: http://localhost:59838/tests/v8_7/run.html) 05:55:22 INFO - 2016-01-09 05:55:22,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 427548672 05:55:22 INFO - 2016-01-09 05:55:22,093 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,093 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:55:22 INFO - 2016-01-09 05:55:22,093 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: |0;Richards;163.15875346712352 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.50298380221653 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.01785714285717 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5005 05:55:22 INFO - 2016-01-09 05:55:22,094 DEBUG : BROWSER_OUTPUT: |4;RayTrace;948.7666034155598 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: |5;Earley;551.8763796909492 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: |6;Boyer;10468.75 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: |7;RegExp;23511.627906976744 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: |8;Splay;537.6344086021505 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:55:22 INFO - 2016-01-09 05:55:22,095 DEBUG : BROWSER_OUTPUT: __startTimestamp1452347722086__endTimestamp 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.16 Median:163.16 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: Values: 163.2 05:55:22 INFO - 2016-01-09 05:55:22,096 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.50 Median:170.50 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: Values: 170.5 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.02 Median:279.02 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: Values: 279.0 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5005.00 Median:5005.00 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,097 DEBUG : BROWSER_OUTPUT: Values: 5005.0 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:948.77 Median:948.77 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: Values: 948.8 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:551.88 Median:551.88 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: Values: 551.9 05:55:22 INFO - 2016-01-09 05:55:22,098 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10468.75 Median:10468.75 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: Values: 10468.8 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23511.63 Median:23511.63 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: Values: 23511.6 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:537.63 Median:537.63 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,099 DEBUG : BROWSER_OUTPUT: Values: 537.6 05:55:22 INFO - 2016-01-09 05:55:22,100 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,100 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 05:55:22 INFO - 2016-01-09 05:55:22,100 DEBUG : BROWSER_OUTPUT: Values: 4822.1 05:55:22 INFO - 2016-01-09 05:55:22,100 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:55:22 INFO - 2016-01-09 05:55:22,100 DEBUG : BROWSER_OUTPUT: 05:55:22 INFO - 2016-01-09 05:55:22,761 INFO : Browser exited with error code: 0 05:55:22 INFO - 2016-01-09 05:55:22,779 INFO : Completed test v8_7 (00:00:38) 05:55:23 INFO - 2016-01-09 05:55:23,120 INFO : Completed test suite (00:20:04) 05:55:23 INFO - 2016-01-09 05:55:23,120 DEBUG : Working with test: dromaeo_css 05:55:23 INFO - 2016-01-09 05:55:23,120 DEBUG : Generating results file: dromaeo_css 05:55:23 INFO - 2016-01-09 05:55:23,122 DEBUG : Working with test: kraken 05:55:23 INFO - 2016-01-09 05:55:23,122 DEBUG : Generating results file: kraken 05:55:23 INFO - 2016-01-09 05:55:23,123 INFO : javascript benchmark 05:55:23 INFO - 2016-01-09 05:55:23,123 DEBUG : Working with test: v8_7 05:55:23 INFO - 2016-01-09 05:55:23,123 DEBUG : Generating results file: v8_7 05:55:23 INFO - 2016-01-09 05:55:23,124 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:55:23 INFO - 2016-01-09 05:55:23,182 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 05:55:23 INFO - 2016-01-09 05:55:23,182 DEBUG : process_Request line: dromaeo_css 7263.90 graph.html#tests=[[72,53,35]] 05:55:23 INFO - 2016-01-09 05:55:23,183 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:55:23 INFO - 2016-01-09 05:55:23,212 DEBUG : process_Request line: kraken 1458.50 graph.html#tests=[[232,53,35]] 05:55:23 INFO - 2016-01-09 05:55:23,212 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:55:23 INFO - 2016-01-09 05:55:23,332 DEBUG : process_Request line: v8_7 23335.30 graph.html#tests=[[230,53,35]] 05:55:23 INFO - 2016-01-09 05:55:23,334 INFO : javascript benchmark 05:55:23 INFO - 2016-01-09 05:55:23,337 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3278.3443311337733, 3351.3967485852536, 3381.949270760939, 3391.6438773928735, 3412.7952322860624, 5542.944570554293, 5565.805196818113, 5567.19275705022, 5571.498565129137, 5603.215549823028, 5583.190437386586, 5589.580781441387, 5605.075162598177, 5611.186377975191, 5614.073677843151, 4888.926666100011, 5073.518015788503, 5096.949030509684, 5149.459306772772, 5202.453742357055, 4948.406191257036, 5094.923576146361, 5128.307678463416, 5131.153359695646, 5132.692038477703, 5003.499650034985, 5079.974600126994, 5111.284420181178, 5118.820841270556, 5134.075866344076, 5115.0, 5125.538701516844, 5125.615578831603, 5167.586593072545, 5190.92213616796, 4833.419989601235, 4884.120858245515, 4970.105381031413, 5095.5159259870625, 5101.362329708786, 5355.758990845421, 5382.811601593965, 5391.81128660495, 5392.083345831218, 5398.2982212312545, 5953.196318496991, 5953.3748956359395, 5959.910601340984, 5965.343812180657, 5967.8209653710455, 5842.532597392198, 5860.033094539396, 5936.673482958436, 5991.4308140727, 6002.129691194796, 5548.6948217848, 5634.690092044936, 5643.971780141073, 5644.181593668936, 5650.0959846424785, 6073.848153796191, 6092.0, 6096.298925623521, 6097.75608975636, 6099.542534309944, 5744.339400968858, 5780.653160810365, 5840.941590584125, 5869.823905282763, 5893.764249430061, 5993.910091348634, 5997.2803263607775, 6006.609570377911, 6008.218931538872, 6010.008348622516, 5728.0, 5968.134620480006, 5973.0, 5974.3428222895445, 5975.701214939322, 5898.085796701518, 5978.372270911578, 6048.0, 6082.2093127893095, 6093.6039157454625, 4628.236341003697, 4637.118947399983, 4638.304254361873, 4644.465886423037, 4663.953360466419, 4156.0, 4165.687573432005, 4175.0, 4181.853635122817, 4193.391958166048, 5517.144842549331, 5593.944060559424, 5632.493075623213, 5709.3434255060365, 5709.7716091356715, 6035.6076855004285, 6037.909431358533, 6040.667763272974, 6043.576949613484, 6070.0, 5481.917771233435, 5768.711564421762, 5769.682667453247, 5775.220345253337, 5777.24895763548, 5041.395032596112, 5122.154844450699, 5131.897362052738, 5142.717150556755, 5194.246834209019, 6209.441150296405, 6269.216347956505, 6273.404026617464, 6274.717637706314, 6277.0, 5918.526517878646, 6030.48740857032, 6075.27096748393, 6080.665563394055, 6082.969585152223, 5925.4370834770625, 6015.0, 6018.8796224074395, 6025.969870150621, 6040.818775436744, 5839.445252700986, 5905.055191169392, 5909.320428150818, 5915.142304365929, 5931.436513531207, 5860.2967643883, 5892.823215303548, 5977.1034344846885, 5978.192943952685, 5981.282246130492, 5867.471927526543, 5949.8215053548465, 5955.67243801595, 5962.70186490665, 5963.671998040149, 5913.46778789911, 5918.970405147947, 5922.881542369042, 5936.970315148396, 5939.227900372924, 5503.862403439946, 5631.042722737063, 5632.211490391266, 5754.136879467946, 5818.272715910512, 5882.470577648032, 5888.764449421975, 5905.0, 5911.497522710446, 5921.585489015728, 5764.07774756037, 5796.478316951495, 5797.8550536236935, 5802.6808525531505, 5802.767889284381], "unit": "ms", "name": "dojo.html", "value": 5518.08148980451}, {"lowerIsBetter": false, "replicates": [590.3683059126733, 625.696537179468, 630.3098107572207, 632.8797528469589, 656.4354654996705, 35646.82176589117, 44648.77675611622, 45315.77342113285, 45331.773341133245, 45361.319580206284, 48554.51445485544, 49114.0, 49153.754231228886, 49603.0, 49731.50268497314, 8178.386621003418, 8328.292095171917, 9220.492872891988, 9362.157405833474, 10707.732306692316, 10907.945460272727, 11046.0, 11048.337099774039, 11050.171237157205, 11050.171237157245, 10980.396078215696, 11071.667849964513, 11086.16853735969, 11087.2238943274, 11088.944555277212, 10989.505472253728, 11036.834447483294, 11042.668719938454, 11043.061339786127, 11099.667009989713, 10775.299605525615, 10902.182336324808, 10907.727306817314, 10944.726381840437, 10947.890521094767, 44589.55410445887, 44980.77509612431, 45001.54998450007, 45051.324230136575, 45072.54927450716, 3833.195029043904, 4006.2988976928923, 4281.12236991416, 4472.083222939322, 4483.775811209459, 33233.169170770685, 33579.496307555404, 33660.326793464235, 33991.0, 33995.32009359824, 3748.11919198988, 3779.111908701453, 3808.0, 3813.8474461021524, 3904.2581909437117, 328138.9999999976, 328450.0, 328655.0, 329438.0, 330554.0, 60889.39110608926, 61797.69101154465, 70782.64608676924, 71047.64476177585, 71163.64418177876, 83464.58267708623, 85214.0, 85518.14481855226, 85628.57185714031, 85643.0, 18017.549561261072, 18154.455366339032, 18187.727184092248, 18208.63582728338, 18213.271469141087, 13810.171389716448, 13842.238676872666, 13866.722665546633, 13878.167309961233, 13881.4447422104, 22639.434014149778, 22732.31803045911, 23037.88481057584, 23109.88445057764, 23162.884185578965, 8594.87107693385, 8969.37214394986, 8988.820223595621, 9010.14403631654, 9044.321675874337, 997.2697370062936, 1043.4313299251926, 1089.515165751249, 1127.3315493070302, 1150.2523359816187, 29752.107436776932, 29779.255518612208, 29882.85058574693, 30599.694003059685, 30657.84671076675, 1110.783397237547, 1166.9905531715006, 1176.4941075337515, 1202.6053146437434, 1265.949362025527, 15068.321925513379, 16213.0, 16405.42581009683, 16423.0, 17654.558636033944, 472305.0, 477397.0, 479065.0, 480410.0, 480551.0, 63472.04791928125, 63755.36244637587, 63796.36203637904, 64174.67912660407, 64181.358186418474, 1314.4873499335267, 1411.6329754263966, 1432.1621851217155, 1470.7132109238596, 1495.6784291377405, 31786.0, 32064.67935320617, 32318.0, 33230.667693323725, 35748.106297342776, 56936.0, 57775.711121444125, 57805.0, 57825.71087144706, 57959.710201448725, 364605.0, 365260.0, 366871.0, 366963.0, 369061.0, 47294.76352618215, 52585.474145259584, 52606.0, 52827.47172528225, 53439.19841202307, 49757.0, 50490.0, 50565.0, 50757.746211268706, 50928.490715092375, 40653.59346406498, 42099.0, 42128.3680744783, 42593.0, 42760.0, 40914.38628420517, 41452.37821432621, 41800.79099604482, 45144.54855451403, 48222.517774821805, 43533.78233108814, 44413.33379999238, 44480.77759611181, 45168.32247516356, 45261.32108018448, 42444.0, 43976.0, 44012.559874400846, 44131.33802993022, 44646.3303050448, 33002.17494562655, 33223.16942076467, 33269.33461330712, 33354.0, 33405.05274847315], "unit": "ms", "name": "ext.html", "value": 22106.043069160805}, {"lowerIsBetter": false, "replicates": [379.45169230461977, 382.98468061277543, 386.3065796894574, 386.5709062940134, 388.64827331265195, 15550.568088094895, 18170.63658726826, 18212.635747285047, 19530.804691953057, 19557.902210488945, 13402.66493337667, 13707.109037912529, 13832.23922684252, 19266.133024013918, 19438.708419373685, 4873.074115917973, 4985.277134815449, 4985.551300382965, 4986.825461108862, 4991.450940396553, 3851.133494963639, 4004.2391945530403, 4969.726665033421, 5266.210068489738, 5412.107002344628, 5311.362636483627, 5337.839864804062, 5343.519083282504, 5348.465153484659, 5364.91952620711, 5240.764165612538, 5381.865453363658, 5433.619646624718, 5434.130539113723, 5438.456154384569, 4684.086603112394, 4720.504347043579, 4729.0, 4733.077049975256, 4734.0, 15401.537953861402, 15685.451009214623, 15708.371665133382, 15715.528534143994, 15805.604859878595, 2092.204962114387, 2150.9139634414605, 2182.6289530779645, 2219.1678120704737, 2281.441046943493, 11013.2841365311, 11055.0, 11065.778684426266, 11146.108311335154, 11387.658370248906, 1760.3662681434673, 1791.973120403195, 1830.1215416599973, 1872.6254749050245, 1918.098493707955, 66068.0, 66227.99999999952, 67734.32265677379, 68161.0, 68413.65793171052, 7966.362690984707, 8034.15641357649, 8092.352611791042, 8101.675932962616, 8137.349012079019, 9825.45959972194, 9838.409695418155, 9841.45871977048, 9847.901520984698, 9855.950720246354, 6443.001334793114, 6445.41991220783, 6453.354664533509, 6476.255230648536, 6508.739650414025, 6700.0, 6700.6984685688285, 6708.899366509506, 6740.831479213059, 6753.696083676246, 3695.427208782643, 3760.5863355030924, 3778.1688028633657, 3784.0729021389934, 3795.2978698940233, 1256.7109564800148, 1285.2417073926429, 1295.4947570447532, 1325.648703093681, 1331.8867896228928, 525.4745254745254, 533.714462762428, 556.1102236421693, 558.7401858135905, 578.124141924984, 11694.122940779476, 13430.462781488826, 13678.247696376795, 13734.381952812148, 13841.377138028813, 563.4056070845215, 571.865611581278, 578.2800413485154, 582.1442479555047, 606.9605475644069, 7217.278272172845, 7490.5131166474, 7513.624318784039, 7622.69509219625, 7644.3120119188425, 630141.0, 630323.0, 635308.0, 642093.0, 647713.0, 80874.0, 82798.0, 84452.0, 86133.56933215294, 86894.56552717196, 603.8067818298102, 604.9122877182784, 609.0225188572366, 609.1654433426146, 621.2358798677652, 12394.256344619353, 12428.316442595744, 12515.12394132402, 12537.373131343214, 12545.623631291075, 38426.42360364541, 38767.612323877525, 38945.41581876217, 39239.803800980815, 39532.011699707735, 53375.199372010225, 53391.0, 53702.462975369745, 54461.72769136129, 54613.0, 7798.688052477837, 7842.529448232896, 7898.565578893216, 7900.68397264126, 7930.881036784337, 10968.561257549609, 13669.0, 14110.0, 14153.858461415253, 14262.144271343752, 10897.237193396386, 11674.824877627012, 11682.824757628472, 11967.521299148284, 12001.459934303151, 11682.649520514058, 11782.941085294518, 11844.940775296069, 11964.042876570027, 12303.200291980993, 12202.450889710162, 12324.938375308066, 12383.566575169825, 12528.937355313165, 12567.0, 7661.195574464601, 7677.347425468674, 7709.383249340151, 7717.536947783151, 7785.14363420012, 11782.646520604396, 11918.702032448911, 12020.879791202326, 12190.817137742764, 12318.0], "unit": "ms", "name": "jquery.html", "value": 7800.154073209199}, {"lowerIsBetter": false, "replicates": [1540.7765873948285, 1764.2325588369056, 1810.777725035601, 1866.8518860900551, 1872.3633964452083, 7249.093863267091, 7322.1213454385415, 7785.0, 8105.878411823827, 8123.1470695577, 7773.378129749621, 7976.641051152697, 8150.225728555793, 8155.021397432316, 8163.142869998654, 2396.4488167721393, 2403.146882856587, 2428.429319110008, 2428.9514209715794, 2430.1980346485616, 2117.089651449876, 2136.3484137338105, 2333.57995560799, 2363.444590521226, 2383.535210633927, 2379.8334116611845, 2386.737458879522, 2388.522295540899, 2388.79695225906, 2389.82076344274, 2356.6582845487396, 2370.5021945391404, 2371.2174982255815, 2373.311739595515, 2374.584447721642, 2358.481134150484, 2422.3338581889943, 2424.3939015246187, 2424.648425978241, 2425.4300239443714, 7756.418268629819, 7798.103218129931, 7802.33680137189, 7803.648835802403, 7806.687732490694, 5118.385793704742, 5187.144121219994, 5190.351206099238, 5193.324867767166, 5197.558207552363, 3202.519622056687, 3231.88688395905, 3299.1257316811066, 3313.287643156733, 3313.5029745538122, 1072.7050061233144, 1079.0666073846146, 1082.1396989393372, 1082.6309414934344, 1092.4264761000459, 10842.241043126984, 10843.186760992958, 10846.837297440545, 10918.781624367548, 10957.780844383147, 4555.202839503074, 4603.286490593963, 4609.285560738091, 4736.313234580966, 4739.123262196505, 9394.201492873182, 9404.811903762024, 9405.153536181777, 9405.153536181777, 9427.198688111588, 5171.50870667286, 5188.714620695822, 5197.636165468381, 5198.454162312978, 5200.089984252741, 4730.030343779517, 4746.7389293589185, 4751.168545504592, 4751.3348131261655, 4754.7147171169845, 3604.4232922732235, 3960.881173564798, 3978.144698889753, 3986.5614782373914, 4068.8372465101656, 1105.160078340467, 1220.4263995921901, 1230.163488827588, 1236.208826351118, 1295.6048405236493, 1244.4089057697522, 1300.7138429545485, 1462.144645382442, 1464.6777708904024, 1469.4709904434394, 7920.089189743138, 7991.800204994922, 8006.919930800734, 8012.919870801334, 8013.559254240956, 1194.5699548162654, 1201.9699507512207, 1220.4995951659955, 1238.38699843578, 1239.5227837282712, 6350.206224221973, 6392.520560957947, 6433.131527243856, 6438.6136831790245, 6440.033994900802, 9740.17208537268, 9880.555375008142, 9883.406995580288, 9888.258380621339, 9901.0, 9627.085426884434, 9789.21686265097, 9803.166730827817, 9818.75453113678, 9903.752406189758, 7669.271419215165, 7737.497062690907, 7741.148473667892, 7744.0, 7748.22517748232, 7393.112826460859, 7393.112826460859, 7394.260573942563, 7414.62926853666, 7599.848003040019, 8634.5251011195, 8757.956210218908, 8837.469751814655, 8851.734447966572, 8856.114388561093, 8776.122387761428, 8991.05593912656, 9017.323700722603, 9018.09819018093, 9054.456732596065, 7445.4415918805225, 7495.063117110361, 7505.924940750741, 7507.174210836913, 7510.061242344707, 6943.27095654949, 7064.046353742179, 7885.763427097197, 8072.152423995631, 8072.515649061075, 7287.890681639886, 7344.853102938018, 7471.551706897603, 7504.962475187589, 7694.576798276148, 7140.714371425085, 7166.713331466683, 7418.146913105062, 7559.584222867795, 7564.319211271012, 7221.45839062083, 7638.274363935418, 7683.0, 7687.192844751221, 7710.190430004769, 7052.753153639598, 7104.893426598502, 7105.32499412555, 7107.644617768988, 7117.679704413417, 7038.401735852305, 7077.292270772881, 7123.750668726777, 7129.180144283267, 7132.108486439195], "unit": "ms", "name": "mootools.html", "value": 4784.95423163855}, {"lowerIsBetter": false, "replicates": [363.9090227443139, 365.66176286934586, 367.99816000920026, 371.5726914048843, 373.78507358268985, 5824.388439213885, 5876.823695289143, 5915.792947246847, 5922.407759224076, 5942.5840191186635, 5759.971200143993, 5766.0, 5807.796727114542, 5811.680357580342, 5832.533397328214, 4635.721856688592, 4649.349091127245, 4650.232711602582, 4659.487456379812, 4662.160811054009, 4514.954850451502, 4560.657950653697, 4569.200389931765, 4627.3521706961055, 4664.0, 4552.9089418211615, 4579.427571553556, 4615.353850460938, 4616.49218585957, 4616.6306695464455, 4588.220002599566, 4592.701474404186, 4595.517470665583, 4597.54024597541, 4600.516945720685, 4096.672266218696, 4100.0, 4100.815463304129, 4104.42538044674, 4107.199096176247, 5536.114221724545, 5597.468240517144, 5664.235328230678, 5679.971600141973, 5680.6591604503865, 5405.567554595623, 5410.594205434608, 5413.404525502192, 5414.539764120054, 5418.349798024262, 6283.277423096311, 6288.088227207029, 6291.5281353898645, 6295.370462953714, 6302.55882088254, 4400.889977750549, 4454.2873140297725, 4506.301523263899, 4533.138703646329, 4546.636269098464, 6310.558260921737, 6319.968400158015, 6329.556931014785, 6353.459955903753, 6393.8401539961405, 4630.236011058205, 4634.026854360554, 4640.071985602893, 4641.327007583882, 4648.465426475931, 4619.376384188159, 4695.460022097503, 4702.31816386622, 4703.0, 4741.2651039089, 5361.839144825662, 5396.244525766396, 5396.379416367089, 5400.486953739389, 5402.135658294654, 6285.434310911948, 6321.431071203614, 6331.461825744863, 6333.0, 6341.175647165839, 1718.3985605038138, 1720.7849018872641, 1725.2495164603438, 1725.284007137024, 1725.7583938248656, 1142.4344949250174, 1157.253571446429, 1161.848959635259, 1168.1589255735857, 1187.524990003988, 6170.475509581735, 6175.44421002112, 6176.413240742122, 6180.165677633552, 6181.474574661204, 6432.35676432353, 6465.806025819139, 6483.448906842877, 6483.740650374027, 6487.124238227779, 6030.939690603125, 6146.661933593696, 6158.630482171084, 6160.907586386208, 6162.476189523851, 3536.80547569881, 3564.3049605326714, 3566.5006899034156, 3567.8751243706347, 3569.1077230692326, 6483.7730679425995, 6558.0, 6562.212534495891, 6564.540482166298, 6575.572587781779, 6573.1783527059115, 6583.80248592543, 6586.769463068866, 6587.571807832475, 6590.472762179014, 6535.607863528204, 6641.50188735837, 6656.96671516649, 6658.1011563438315, 6666.766663166766, 6258.217722784652, 6395.872082558416, 6405.679716014274, 6433.903491447539, 6449.516286278454, 6265.060240963892, 6289.874202516015, 6352.745890164341, 6353.491720662429, 6360.363963603602, 6636.601803891782, 6672.633005184762, 6677.232118306457, 6681.899771503334, 6705.798826035227, 4523.434570678665, 4543.273076307775, 4547.40883685132, 4548.47692515365, 4549.545045495424, 6274.874502509833, 6386.457151142205, 6416.935830641821, 6444.838879027874, 6549.770758023446, 6321.11504389395, 6354.26925903527, 6354.6504942226775, 6383.457406120532, 6386.680665966776, 6345.048242763438, 6353.205849268841, 6361.045843123568, 6373.075903993988, 6389.744410223725, 6135.969320153549, 6219.968900155471, 6227.688615569294, 6229.12792209082, 6232.906506402317, 6347.492200624031, 6355.555111142361, 6356.5232607555545, 6361.586496877688, 6364.07720880479, 6101.267847858286, 6191.25704915413, 6199.442050215503, 6202.937970620416, 6210.658413787104], "unit": "ms", "name": "prototype.html", "value": 4812.317184130372}, {"lowerIsBetter": false, "replicates": [915.1854849184227, 934.2712684106399, 942.1473566422387, 944.5607792376544, 944.8629948657444, 13997.090189137713, 14195.503157389468, 14206.928965355159, 14227.85772142277, 14276.357563909623, 12692.238465692042, 12781.169224000456, 12786.16889902158, 12793.872061279384, 12811.551595694153, 763.6868883757661, 777.2965466253022, 907.419251678926, 907.5462268865583, 914.1818072824818, 920.6593560382668, 920.6915683246087, 920.7467946314783, 927.1331305229631, 927.3091546797611, 973.0221127766584, 976.8827740671094, 979.2019504104168, 982.5676702250987, 982.8132654795602, 800.3277247112425, 812.5327936436543, 960.4429430930079, 973.7176218896511, 975.3855071305103, 719.1909102259957, 722.288545782404, 722.4220623501178, 722.5664601239267, 723.2442098007542, 13561.25413102279, 13582.049256552045, 13644.181349119084, 13736.381862816197, 13755.31223438834, 12053.457594408274, 12524.373781310898, 12584.74830503394, 12642.620721378373, 12716.745665086739, 9659.565319560566, 9802.117809397188, 9853.261005424622, 10000.099991000845, 10004.0, 3416.231347946717, 3438.9828050859587, 3445.8277086145595, 3477.6522347765276, 3535.982320088409, 54274.72862635701, 54425.455745442436, 54483.72758136223, 56574.717126414514, 56725.71637141788, 10193.541290641939, 10204.795904081877, 10230.386176829265, 10317.587296508205, 10371.636992705218, 17289.82710172907, 17329.2201850917, 17393.739093913602, 17435.128243588024, 17437.7384339235, 8105.6757729691335, 8128.0, 8262.091169971298, 8267.255946964802, 8301.958490207631, 4469.21788686985, 4545.045540436479, 4579.473360563577, 4591.8392856249875, 4595.287730401793, 3293.275479394529, 3330.0842268375904, 3335.24956884699, 3337.749668774803, 3340.0313908966614, 498.2277469921607, 510.18879980829917, 511.50639632753825, 512.1575009109994, 522.4618642797924, 498.16059939002895, 674.3389247269148, 678.4097834883585, 760.9885851712229, 763.5838468034934, 10020.799584008279, 10211.795764084825, 10215.489225538693, 10259.333143345659, 10358.171346292278, 657.5988646925372, 700.6846918886522, 732.1031736123313, 752.6349720385608, 759.8214419611387, 4453.844115456009, 4456.398386217884, 4477.776111194493, 4529.569690879362, 4535.659825513099, 70797.2920270801, 71928.64035679789, 72187.0, 72323.63838180776, 72341.63829180821, 61129.0, 61211.38788612057, 61489.38510614926, 61576.38423615796, 62280.06579901305, 55216.723916380164, 55220.72389638026, 55319.0, 55446.72276638591, 56830.43169568334, 8824.470531768115, 9012.684556040507, 9064.093590640883, 9158.129977652112, 9173.724788256362, 40308.39537406882, 40309.39535906905, 40449.595504044584, 40679.1864162721, 41341.173176536904, 46709.31356430431, 52279.477205227464, 52691.47308527019, 52981.47018529765, 53207.0, 8138.552379618941, 8190.385721070825, 8291.253787159185, 8323.542205178774, 8451.112633173674, 12090.274583525017, 12130.821025303, 12294.262344259372, 12405.875941240472, 12555.874441255835, 10791.35251884858, 10813.62152324665, 10854.891451085388, 10863.619773307897, 11091.389973551532, 11665.708357291136, 11723.941380293043, 11768.176227663982, 11819.40902954866, 11887.227330223508, 10660.946695266784, 11203.495842686936, 11353.772924541629, 11705.180637355303, 12070.27578345302, 10944.01503864656, 11001.724956876142, 11044.779104418027, 11140.220184587, 11285.605003824827, 10205.489725513844, 10361.274710770174, 10363.740906477398, 10407.531661075422, 10442.321249118782], "unit": "ms", "name": "yui.html", "value": 6704.157972764929}], "name": "dromaeo_css", "value": 7263.90142687202}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 104.0, 109.0, 110.0, 111.0, 100.0, 109.0, 99.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.7}, {"lowerIsBetter": true, "replicates": [150.0, 124.0, 147.0, 162.0, 129.0, 148.0, 146.0, 122.0, 156.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.7}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 160.0, 162.0, 159.0, 159.0, 161.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 153.0, 71.0, 71.0, 71.0, 71.0, 152.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.2}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 131.0, 90.0, 90.0, 89.0, 129.0, 89.0, 89.0, 140.0], "unit": "ms", "name": "audio-oscillator", "value": 102.5}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 147.0, 144.0, 144.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 55.0, 54.0, 55.0, 61.0, 55.0, 63.0, 54.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 56.1}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 69.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.3}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 77.0, 85.0, 85.0, 78.0, 78.0, 77.0, 85.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.5}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 108.0, 105.0, 114.0, 117.0, 119.0, 113.0, 116.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.6}, {"lowerIsBetter": true, "replicates": [136.0, 139.0, 136.0, 136.0, 135.0, 138.0, 138.0, 134.0, 137.0, 150.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.9}, {"lowerIsBetter": true, "replicates": [61.0, 63.0, 61.0, 64.0, 65.0, 61.0, 62.0, 60.0, 60.0, 60.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.7}], "name": "kraken", "value": 1458.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.15875346712352], "unit": "score", "name": "Richards", "value": 216.36595800000006}, {"lowerIsBetter": false, "replicates": [170.50298380221653], "unit": "score", "name": "DeltaBlue", "value": 387.78207000000015}, {"lowerIsBetter": false, "replicates": [279.01785714285717], "unit": "score", "name": "Encrypt", "value": 953.9927040000001}, {"lowerIsBetter": false, "replicates": [5005.0], "unit": "score", "name": "Decrypt", "value": 53.18301698301701}, {"lowerIsBetter": false, "replicates": [948.7666034155598], "unit": "score", "name": "RayTrace", "value": 779.9484060000001}, {"lowerIsBetter": false, "replicates": [551.8763796909492], "unit": "score", "name": "Earley", "value": 1207.6309559999997}, {"lowerIsBetter": false, "replicates": [10468.75], "unit": "score", "name": "Boyer", "value": 63.66213731343284}, {"lowerIsBetter": false, "replicates": [23511.627906976744], "unit": "score", "name": "RegExp", "value": 38.746147378832845}, {"lowerIsBetter": false, "replicates": [537.6344086021505], "unit": "score", "name": "Splay", "value": 151.57326}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23335.30329139125}]} 05:55:23 INFO - RETURN: dromaeo_css: 7263.90 05:55:23 INFO - RETURN: kraken: 1458.50 05:55:23 INFO - RETURN: v8_7: 23335.30 05:55:23 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23335.30"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7263.90"}}} 05:55:23 INFO - Return code: 0 05:55:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:55:23 INFO - # TBPL SUCCESS # 05:55:23 INFO - Running post-action listener: _resource_record_post_action 05:55:23 INFO - Running post-run listener: _resource_record_post_run 05:55:24 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 315392; Write bytes: 506204160; Read time: 44; Write time: 442844 05:55:24 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 244756480; Read time: 0; Write time: 286472 05:55:24 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 315392; Write bytes: 261447680; Read time: 44; Write time: 156372 05:55:24 INFO - Running post-run listener: _upload_blobber_files 05:55:24 INFO - Blob upload gear active. 05:55:24 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:55:24 INFO - Copying logs to upload dir... 05:55:24 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.831108 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-01-09 05:55:24.387749) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-09 05:55:24.391213) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010120 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452338132/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-09 05:55:24.443119) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:55:24.443494) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452341801.162635-2086747084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 05:55:24.492912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-09 05:55:24.493285) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-09 05:55:24.493649) ========= ========= Total master_lag: 0.23 =========