builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1452136895.41 results: success (0) buildid: 20160106170516 builduid: 81609b82b55c45adb396a8470012affb revision: faa80a5c54b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.407228) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.407677) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.408012) ========= 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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006354 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.433174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.433478) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.447305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.447602) ========= 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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.497699) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.498003) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.498358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.498657) ========= 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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-06 19:21:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-01-06 19:21:35 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101587 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.612902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.613240) ========= 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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022402 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:35.673557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-06 19:21:35.673905) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev faa80a5c54b0 --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 faa80a5c54b0 --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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-06 19:21:35,996 Setting DEBUG logging. 2016-01-06 19:21:35,996 attempt 1/10 2016-01-06 19:21:35,996 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/faa80a5c54b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-06 19:21:36,722 unpacking tar archive at: mozilla-beta-faa80a5c54b0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-06 19:21:36.692909) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:36.693649) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:36.705528) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:36.706584) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-06 19:21:36.707947) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-01-06 19:21:36.709042) ========= /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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:21:37 INFO - MultiFileLogger online at 20160106 19:21:37 in /builds/slave/test-pgo 19:21:37 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 19:21:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:21:37 INFO - {'append_to_log': False, 19:21:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:21:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:21:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:21:37 INFO - 'branch': 'Mozilla-Beta', 19:21:37 INFO - 'buildbot_json_path': 'buildprops.json', 19:21:37 INFO - 'config_files': ('talos/linux_config.py',), 19:21:37 INFO - 'default_actions': ('clobber', 19:21:37 INFO - 'read-buildbot-config', 19:21:37 INFO - 'download-and-extract', 19:21:37 INFO - 'populate-webroot', 19:21:37 INFO - 'create-virtualenv', 19:21:37 INFO - 'install', 19:21:37 INFO - 'run-tests'), 19:21:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:21:37 INFO - 'download_minidump_stackwalk': True, 19:21:37 INFO - 'download_symbols': 'ondemand', 19:21:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:21:37 INFO - 'tooltool.py': '/tools/tooltool.py', 19:21:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:21:37 INFO - '/tools/misc-python/virtualenv.py')}, 19:21:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:21:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:21:37 INFO - 'installer_path': 'installer.exe', 19:21:37 INFO - 'log_level': 'info', 19:21:37 INFO - 'log_name': 'talos', 19:21:37 INFO - 'log_to_console': True, 19:21:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:21:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:21:37 INFO - 'opt_config_files': (), 19:21:37 INFO - 'pip_index': False, 19:21:37 INFO - 'sps_profile': False, 19:21:37 INFO - 'sps_profile_interval': 0, 19:21:37 INFO - 'suite': 'dromaeojs', 19:21:37 INFO - 'system_bits': '32', 19:21:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:21:37 INFO - 'title': 'talos-linux64-ix-008', 19:21:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:21:37 INFO - 'use_talos_json': True, 19:21:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:21:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:21:37 INFO - 'work_dir': 'build'} 19:21:37 INFO - ##### 19:21:37 INFO - ##### Running clobber step. 19:21:37 INFO - ##### 19:21:37 INFO - Running pre-action listener: _resource_record_pre_action 19:21:37 INFO - Running main action method: clobber 19:21:37 INFO - rmtree: /builds/slave/test-pgo/build 19:21:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:21:38 INFO - Running post-action listener: _resource_record_post_action 19:21:38 INFO - ##### 19:21:38 INFO - ##### Running read-buildbot-config step. 19:21:38 INFO - ##### 19:21:38 INFO - Running pre-action listener: _resource_record_pre_action 19:21:38 INFO - Running main action method: read_buildbot_config 19:21:38 INFO - Using buildbot properties: 19:21:38 INFO - { 19:21:38 INFO - "properties": { 19:21:38 INFO - "buildnumber": 68, 19:21:38 INFO - "product": "firefox", 19:21:38 INFO - "script_repo_revision": "production", 19:21:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 19:21:38 INFO - "repository": "", 19:21:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 19:21:38 INFO - "buildid": "20160106170516", 19:21:38 INFO - "slavename": "talos-linux64-ix-008", 19:21:38 INFO - "pgo_build": "True", 19:21:38 INFO - "basedir": "/builds/slave/test-pgo", 19:21:38 INFO - "project": "", 19:21:38 INFO - "platform": "ubuntu64_hw", 19:21:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:21:38 INFO - "slavebuilddir": "test", 19:21:38 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 19:21:38 INFO - "branch": "mozilla-beta", 19:21:38 INFO - "repo_path": "releases/mozilla-beta", 19:21:38 INFO - "stage_platform": "linux64-pgo", 19:21:38 INFO - "builduid": "81609b82b55c45adb396a8470012affb", 19:21:38 INFO - "revision": "faa80a5c54b0" 19:21:38 INFO - }, 19:21:38 INFO - "sourcestamp": { 19:21:38 INFO - "repository": "", 19:21:38 INFO - "hasPatch": false, 19:21:38 INFO - "project": "", 19:21:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 19:21:38 INFO - "changes": [ 19:21:38 INFO - { 19:21:38 INFO - "category": null, 19:21:38 INFO - "files": [ 19:21:38 INFO - { 19:21:38 INFO - "url": null, 19:21:38 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2" 19:21:38 INFO - } 19:21:38 INFO - ], 19:21:38 INFO - "repository": "", 19:21:38 INFO - "rev": "faa80a5c54b0", 19:21:38 INFO - "who": "sendchange", 19:21:38 INFO - "when": 1452136875, 19:21:38 INFO - "number": 7427069, 19:21:38 INFO - "comments": "Bug 1212298 - Use inner script instead of outer script in CodeGenerator::visitCallDirectEval. r=shu a=ritu", 19:21:38 INFO - "project": "", 19:21:38 INFO - "at": "Wed 06 Jan 2016 19:21:15", 19:21:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 19:21:38 INFO - "revlink": "", 19:21:38 INFO - "properties": [ 19:21:38 INFO - [ 19:21:38 INFO - "buildid", 19:21:38 INFO - "20160106170516", 19:21:38 INFO - "Change" 19:21:38 INFO - ], 19:21:38 INFO - [ 19:21:38 INFO - "builduid", 19:21:38 INFO - "81609b82b55c45adb396a8470012affb", 19:21:38 INFO - "Change" 19:21:38 INFO - ], 19:21:38 INFO - [ 19:21:38 INFO - "pgo_build", 19:21:38 INFO - "True", 19:21:38 INFO - "Change" 19:21:38 INFO - ] 19:21:38 INFO - ], 19:21:38 INFO - "revision": "faa80a5c54b0" 19:21:38 INFO - } 19:21:38 INFO - ], 19:21:38 INFO - "revision": "faa80a5c54b0" 19:21:38 INFO - } 19:21:38 INFO - } 19:21:38 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2. 19:21:38 INFO - Running post-action listener: _resource_record_post_action 19:21:38 INFO - ##### 19:21:38 INFO - ##### Running download-and-extract step. 19:21:38 INFO - ##### 19:21:38 INFO - Running pre-action listener: _resource_record_pre_action 19:21:38 INFO - Running main action method: download_and_extract 19:21:38 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/test_packages.json 19:21:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:21:38 INFO - Downloaded 1270 bytes. 19:21:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:21:38 INFO - Using the following test package requirements: 19:21:38 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 19:21:38 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 19:21:38 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'jsshell-linux-x86_64.zip'], 19:21:38 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 19:21:38 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 19:21:38 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 19:21:38 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 19:21:38 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 19:21:38 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 19:21:38 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 19:21:38 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 19:21:38 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.common.tests.zip 19:21:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:44 INFO - Downloaded 22572062 bytes. 19:21:44 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 19:21:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 19:21:44 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 19:21:46 INFO - Return code: 0 19:21:46 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 19:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.common.tests.zip 19:21:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:46 INFO - Downloaded 22572062 bytes. 19:21:46 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 19:21:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 19:21:46 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 19:21:47 INFO - Return code: 0 19:21: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')]} 19:21:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 19:21:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:49 INFO - Downloaded 11131970 bytes. 19:21:49 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 19:21:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 19:21:49 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 19:21:50 INFO - Return code: 0 19:21: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')]} 19:21:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2 19:21:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/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 19:21:55 INFO - Downloaded 56628619 bytes. 19:21:55 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2 19:21:55 INFO - mkdir: /builds/slave/test-pgo/properties 19:21:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:21:55 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:21:55 INFO - Contents: 19:21:55 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2 19:21:55 INFO - Running post-action listener: _resource_record_post_action 19:21:55 INFO - Running post-action listener: set_extra_try_arguments 19:21:55 INFO - ##### 19:21:55 INFO - ##### Running populate-webroot step. 19:21:55 INFO - ##### 19:21:55 INFO - Running pre-action listener: _resource_record_pre_action 19:21:55 INFO - Running main action method: populate_webroot 19:21:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:21:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:21:55 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 19:21:55 INFO - '../fennec_ids.txt'], 19:21:55 INFO - 'tests': ['tcheck2']}, 19:21:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:21:55 INFO - '--tppagecycles', 19:21:55 INFO - '7'], 19:21:55 INFO - 'tests': ['tsvgm']}}, 19:21:55 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:21:55 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:21:55 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:21:55 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:21:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'tests': ['damp', 'tps']}, 19:21:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['damp', 'tps']}, 19:21:55 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:21:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:21:55 INFO - 'other': {'tests': ['a11yr', 19:21:55 INFO - 'ts_paint', 19:21:55 INFO - 'tpaint', 19:21:55 INFO - 'sessionrestore', 19:21:55 INFO - 'sessionrestore_no_auto_restore']}, 19:21:55 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['a11yr', 19:21:55 INFO - 'ts_paint', 19:21:55 INFO - 'tpaint', 19:21:55 INFO - 'sessionrestore', 19:21:55 INFO - 'sessionrestore_no_auto_restore']}, 19:21:55 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['a11yr', 19:21:55 INFO - 'ts_paint', 19:21:55 INFO - 'tpaint', 19:21:55 INFO - 'sessionrestore', 19:21:55 INFO - 'sessionrestore_no_auto_restore']}, 19:21:55 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['a11yr', 19:21:55 INFO - 'ts_paint', 19:21:55 INFO - 'tpaint', 19:21:55 INFO - 'sessionrestore', 19:21:55 INFO - 'sessionrestore_no_auto_restore']}, 19:21:55 INFO - 'other_l64': {'tests': ['a11yr', 19:21:55 INFO - 'ts_paint', 19:21:55 INFO - 'tpaint', 19:21:55 INFO - 'sessionrestore', 19:21:55 INFO - 'sessionrestore_no_auto_restore']}, 19:21:55 INFO - 'other_nol64': {'tests': ['a11yr', 19:21:55 INFO - 'ts_paint', 19:21:55 INFO - 'tpaint', 19:21:55 INFO - 'sessionrestore', 19:21:55 INFO - 'sessionrestore_no_auto_restore']}, 19:21:55 INFO - 'svgr': {'tests': ['tsvgx', 19:21:55 INFO - 'tsvgr_opacity', 19:21:55 INFO - 'tart', 19:21:55 INFO - 'tscrollx', 19:21:55 INFO - 'cart']}, 19:21:55 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['tsvgx', 19:21:55 INFO - 'tsvgr_opacity', 19:21:55 INFO - 'tart', 19:21:55 INFO - 'tscrollx', 19:21:55 INFO - 'cart']}, 19:21:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'tests': ['tp5o']}, 19:21:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'talos_options': ['--e10s'], 19:21:55 INFO - 'tests': ['tp5o']}, 19:21:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'talos_options': ['--xperf_path', 19:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:55 INFO - 'tests': ['tp5n']}, 19:21:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:55 INFO - 'talos_options': ['--e10s', 19:21:55 INFO - '--xperf_path', 19:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:55 INFO - 'tests': ['tp5n']}}, 19:21:55 INFO - 'talos.zip': {'path': '', 19:21:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:21:55 INFO - Running post-action listener: _resource_record_post_action 19:21:55 INFO - ##### 19:21:55 INFO - ##### Running create-virtualenv step. 19:21:55 INFO - ##### 19:21:55 INFO - Running pre-action listener: _resource_record_pre_action 19:21:55 INFO - Running main action method: create_virtualenv 19:21:55 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:21:55 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 19:21:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:21:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:21:55 INFO - Using real prefix '/usr' 19:21:55 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:21:56 INFO - Installing distribute.............................................................................................................................................................................................done. 19:21:58 INFO - Installing pip.................done. 19:21:58 INFO - Return code: 0 19:21:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:21:58 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')]} 19:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:58 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')]} 19:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:58 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:21:58 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 19:21:58 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 19:21:58 INFO - Using env: {'DISPLAY': ':0', 19:21:58 INFO - 'HOME': '/home/cltbld', 19:21:58 INFO - 'LANG': 'en_US.UTF-8', 19:21:58 INFO - 'LANGUAGE': 'en_US:en', 19:21:58 INFO - 'LOGNAME': 'cltbld', 19:21:58 INFO - 'MAIL': '/var/mail/cltbld', 19:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:58 INFO - 'MOZ_NO_REMOTE': '1', 19:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:21:58 INFO - 'NO_EM_RESTART': '1', 19:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:21:58 INFO - 'PWD': '/builds/slave/test-pgo', 19:21:58 INFO - 'SHELL': '/bin/bash', 19:21:58 INFO - 'SHLVL': '1', 19:21:58 INFO - 'TERM': 'linux', 19:21:58 INFO - 'TMOUT': '86400', 19:21:58 INFO - 'USER': 'cltbld', 19:21:58 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:58 INFO - '_': '/tools/buildbot/bin/python'} 19:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:58 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 19:21:58 INFO - Cleaning up... 19:21:58 INFO - Return code: 0 19:21:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:21:58 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')]} 19:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:58 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')]} 19:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:58 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:21:58 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 19:21:58 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 19:21:58 INFO - Using env: {'DISPLAY': ':0', 19:21:58 INFO - 'HOME': '/home/cltbld', 19:21:58 INFO - 'LANG': 'en_US.UTF-8', 19:21:58 INFO - 'LANGUAGE': 'en_US:en', 19:21:58 INFO - 'LOGNAME': 'cltbld', 19:21:58 INFO - 'MAIL': '/var/mail/cltbld', 19:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:58 INFO - 'MOZ_NO_REMOTE': '1', 19:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:21:58 INFO - 'NO_EM_RESTART': '1', 19:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:21:58 INFO - 'PWD': '/builds/slave/test-pgo', 19:21:58 INFO - 'SHELL': '/bin/bash', 19:21:58 INFO - 'SHLVL': '1', 19:21:58 INFO - 'TERM': 'linux', 19:21:58 INFO - 'TMOUT': '86400', 19:21:58 INFO - 'USER': 'cltbld', 19:21:58 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:58 INFO - '_': '/tools/buildbot/bin/python'} 19:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:58 INFO - Downloading/unpacking psutil>=0.7.1 19:21:58 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 19:21:58 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 19:21:58 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 19:21:58 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 19:21:58 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 19:21:58 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 19:22:00 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 19:22:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 19:22:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:22:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:00 INFO - Installing collected packages: psutil 19:22:00 INFO - Running setup.py install for psutil 19:22:00 INFO - building 'psutil._psutil_linux' extension 19:22:00 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 19:22:00 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 19:22:00 INFO - building 'psutil._psutil_posix' extension 19:22:00 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 19:22:01 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 19:22:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:01 INFO - Successfully installed psutil 19:22:01 INFO - Cleaning up... 19:22:01 INFO - Return code: 0 19:22:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:01 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:01 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 19:22:01 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 19:22:01 INFO - Using env: {'DISPLAY': ':0', 19:22:01 INFO - 'HOME': '/home/cltbld', 19:22:01 INFO - 'LANG': 'en_US.UTF-8', 19:22:01 INFO - 'LANGUAGE': 'en_US:en', 19:22:01 INFO - 'LOGNAME': 'cltbld', 19:22:01 INFO - 'MAIL': '/var/mail/cltbld', 19:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:01 INFO - 'MOZ_NO_REMOTE': '1', 19:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:01 INFO - 'NO_EM_RESTART': '1', 19:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:01 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:01 INFO - 'SHELL': '/bin/bash', 19:22:01 INFO - 'SHLVL': '1', 19:22:01 INFO - 'TERM': 'linux', 19:22:01 INFO - 'TMOUT': '86400', 19:22:01 INFO - 'USER': 'cltbld', 19:22:01 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:01 INFO - '_': '/tools/buildbot/bin/python'} 19:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:22:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 19:22:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:22:03 INFO - Installing collected packages: mozsystemmonitor 19:22:03 INFO - Running setup.py install for mozsystemmonitor 19:22:03 INFO - Successfully installed mozsystemmonitor 19:22:03 INFO - Cleaning up... 19:22:03 INFO - Return code: 0 19:22:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:22:03 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')]} 19:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:03 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')]} 19:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:03 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:03 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 19:22:03 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 19:22:03 INFO - Using env: {'DISPLAY': ':0', 19:22:03 INFO - 'HOME': '/home/cltbld', 19:22:03 INFO - 'LANG': 'en_US.UTF-8', 19:22:03 INFO - 'LANGUAGE': 'en_US:en', 19:22:03 INFO - 'LOGNAME': 'cltbld', 19:22:03 INFO - 'MAIL': '/var/mail/cltbld', 19:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:03 INFO - 'MOZ_NO_REMOTE': '1', 19:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:03 INFO - 'NO_EM_RESTART': '1', 19:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:03 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:03 INFO - 'SHELL': '/bin/bash', 19:22:03 INFO - 'SHLVL': '1', 19:22:03 INFO - 'TERM': 'linux', 19:22:03 INFO - 'TMOUT': '86400', 19:22:03 INFO - 'USER': 'cltbld', 19:22:03 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:03 INFO - '_': '/tools/buildbot/bin/python'} 19:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:03 INFO - Downloading/unpacking blobuploader==1.2.4 19:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:05 INFO - Downloading blobuploader-1.2.4.tar.gz 19:22:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 19:22:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:22:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 19:22:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:22:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 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 19:22:05 INFO - Downloading docopt-0.6.1.tar.gz 19:22:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 19:22:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:22:05 INFO - Installing collected packages: blobuploader, requests, docopt 19:22:05 INFO - Running setup.py install for blobuploader 19:22:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:22:06 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:22:06 INFO - Running setup.py install for requests 19:22:06 INFO - Running setup.py install for docopt 19:22:06 INFO - Successfully installed blobuploader requests docopt 19:22:06 INFO - Cleaning up... 19:22:06 INFO - Return code: 0 19:22:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:06 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:06 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 19:22:06 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 19:22:06 INFO - Using env: {'DISPLAY': ':0', 19:22:06 INFO - 'HOME': '/home/cltbld', 19:22:06 INFO - 'LANG': 'en_US.UTF-8', 19:22:06 INFO - 'LANGUAGE': 'en_US:en', 19:22:06 INFO - 'LOGNAME': 'cltbld', 19:22:06 INFO - 'MAIL': '/var/mail/cltbld', 19:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:06 INFO - 'MOZ_NO_REMOTE': '1', 19:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:06 INFO - 'NO_EM_RESTART': '1', 19:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:06 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:06 INFO - 'SHELL': '/bin/bash', 19:22:06 INFO - 'SHLVL': '1', 19:22:06 INFO - 'TERM': 'linux', 19:22:06 INFO - 'TMOUT': '86400', 19:22:06 INFO - 'USER': 'cltbld', 19:22:06 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:06 INFO - '_': '/tools/buildbot/bin/python'} 19:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:22:06 INFO - Running setup.py (path:/tmp/pip-Vq8w0Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:22:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:22:06 INFO - Running setup.py (path:/tmp/pip-eudL1n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:22:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:22:06 INFO - Running setup.py (path:/tmp/pip-UURY7g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:22:07 INFO - Running setup.py (path:/tmp/pip-cRF8mK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:22:07 INFO - Running setup.py (path:/tmp/pip-3I6Vjs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:22:07 INFO - Running setup.py (path:/tmp/pip-P7Pa8U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:22:07 INFO - Running setup.py (path:/tmp/pip-OrfYNX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:22:07 INFO - Running setup.py (path:/tmp/pip-goegzO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:22:07 INFO - Running setup.py (path:/tmp/pip-XVXPsl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:22:07 INFO - Running setup.py (path:/tmp/pip-vh8S_Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:22:07 INFO - Running setup.py (path:/tmp/pip-JY4Z8Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:22:07 INFO - Running setup.py (path:/tmp/pip-FWONhN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:22:07 INFO - Running setup.py (path:/tmp/pip-ZHBCxi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:22:07 INFO - Running setup.py (path:/tmp/pip-15CcBJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:22:07 INFO - Running setup.py (path:/tmp/pip-RUAA1s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:22:07 INFO - Running setup.py (path:/tmp/pip-1_1FhQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:22:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:22:07 INFO - Running setup.py (path:/tmp/pip-j0OswL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:22:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:22:08 INFO - Running setup.py install for manifestparser 19:22:08 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Running setup.py install for mozcrash 19:22:08 INFO - Running setup.py install for mozdebug 19:22:08 INFO - Running setup.py install for mozdevice 19:22:08 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Running setup.py install for mozfile 19:22:08 INFO - Running setup.py install for mozhttpd 19:22:08 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Running setup.py install for mozinfo 19:22:08 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Running setup.py install for mozInstall 19:22:08 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:22:08 INFO - Running setup.py install for mozleak 19:22:08 INFO - Running setup.py install for mozlog 19:22:09 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Running setup.py install for moznetwork 19:22:09 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Running setup.py install for mozprocess 19:22:09 INFO - Running setup.py install for mozprofile 19:22:09 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Running setup.py install for mozrunner 19:22:09 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Running setup.py install for mozscreenshot 19:22:09 INFO - Running setup.py install for moztest 19:22:09 INFO - Running setup.py install for mozversion 19:22:09 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:22:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:22:09 INFO - Cleaning up... 19:22:09 INFO - Return code: 0 19:22:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:22:09 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')]} 19:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:09 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')]} 19:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:09 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:09 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 19:22:09 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 19:22:09 INFO - Using env: {'DISPLAY': ':0', 19:22:09 INFO - 'HOME': '/home/cltbld', 19:22:09 INFO - 'LANG': 'en_US.UTF-8', 19:22:09 INFO - 'LANGUAGE': 'en_US:en', 19:22:09 INFO - 'LOGNAME': 'cltbld', 19:22:09 INFO - 'MAIL': '/var/mail/cltbld', 19:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:09 INFO - 'MOZ_NO_REMOTE': '1', 19:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:09 INFO - 'NO_EM_RESTART': '1', 19:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:09 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:09 INFO - 'SHELL': '/bin/bash', 19:22:09 INFO - 'SHLVL': '1', 19:22:09 INFO - 'TERM': 'linux', 19:22:09 INFO - 'TMOUT': '86400', 19:22:09 INFO - 'USER': 'cltbld', 19:22:09 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:09 INFO - '_': '/tools/buildbot/bin/python'} 19:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:22:10 INFO - Running setup.py (path:/tmp/pip-T2JEAP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:22:10 INFO - Running setup.py (path:/tmp/pip-BExoam-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:22:10 INFO - Running setup.py (path:/tmp/pip-tEqoWQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:22:10 INFO - Running setup.py (path:/tmp/pip-tcSjvv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:22:10 INFO - Running setup.py (path:/tmp/pip-BeoUG0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:22:10 INFO - Running setup.py (path:/tmp/pip-OnkJmf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:22:10 INFO - Running setup.py (path:/tmp/pip-tK4vQr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:22:10 INFO - Running setup.py (path:/tmp/pip-iCt_DD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:22:10 INFO - Running setup.py (path:/tmp/pip-1bNk3L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:22:10 INFO - Running setup.py (path:/tmp/pip-_ltmqv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:22:10 INFO - Running setup.py (path:/tmp/pip-kUagJV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:22:10 INFO - Running setup.py (path:/tmp/pip-rKq4oB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:22:10 INFO - Running setup.py (path:/tmp/pip-_4Fu5Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:22:10 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)) 19:22:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:22:10 INFO - Running setup.py (path:/tmp/pip-2ihBbO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:22:11 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)) 19:22:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:22:11 INFO - Running setup.py (path:/tmp/pip-IaZoE_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:22:11 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)) 19:22:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:22:11 INFO - Running setup.py (path:/tmp/pip-nxGzMk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:22:11 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)) 19:22:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:22:11 INFO - Running setup.py (path:/tmp/pip-Daqyj2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:22:11 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)) 19:22:11 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)) 19:22:11 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)) 19:22:11 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)) 19:22:11 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)) 19:22:11 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)) 19:22:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:22:11 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 19:22:11 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 19:22:11 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 19:22:11 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 19:22:11 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 19:22:11 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 19:22:12 INFO - Downloading blessings-1.5.1.tar.gz 19:22:12 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 19:22:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:22:13 INFO - Installing collected packages: blessings 19:22:13 INFO - Running setup.py install for blessings 19:22:13 INFO - Successfully installed blessings 19:22:13 INFO - Cleaning up... 19:22:13 INFO - Return code: 0 19:22:13 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:22:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:22:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:22:13 INFO - Reading from file tmpfile_stdout 19:22:13 INFO - Current package versions: 19:22:13 INFO - argparse == 1.2.1 19:22:13 INFO - blessings == 1.5.1 19:22:13 INFO - blobuploader == 1.2.4 19:22:13 INFO - docopt == 0.6.1 19:22:13 INFO - manifestparser == 1.1 19:22:13 INFO - mozInstall == 1.12 19:22:13 INFO - mozcrash == 0.16 19:22:13 INFO - mozdebug == 0.1 19:22:13 INFO - mozdevice == 0.46 19:22:13 INFO - mozfile == 1.2 19:22:13 INFO - mozhttpd == 0.7 19:22:13 INFO - mozinfo == 0.8 19:22:13 INFO - mozleak == 0.1 19:22:13 INFO - mozlog == 3.0 19:22:13 INFO - moznetwork == 0.27 19:22:13 INFO - mozprocess == 0.22 19:22:13 INFO - mozprofile == 0.27 19:22:13 INFO - mozrunner == 6.11 19:22:13 INFO - mozscreenshot == 0.1 19:22:13 INFO - mozsystemmonitor == 0.0 19:22:13 INFO - moztest == 0.7 19:22:13 INFO - mozversion == 1.4 19:22:13 INFO - psutil == 3.1.1 19:22:13 INFO - requests == 1.2.3 19:22:13 INFO - wsgiref == 0.1.2 19:22:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:22:13 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')]} 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:13 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')]} 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:13 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:13 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 19:22:13 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 19:22:13 INFO - Using env: {'DISPLAY': ':0', 19:22:13 INFO - 'HOME': '/home/cltbld', 19:22:13 INFO - 'LANG': 'en_US.UTF-8', 19:22:13 INFO - 'LANGUAGE': 'en_US:en', 19:22:13 INFO - 'LOGNAME': 'cltbld', 19:22:13 INFO - 'MAIL': '/var/mail/cltbld', 19:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:13 INFO - 'MOZ_NO_REMOTE': '1', 19:22:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:13 INFO - 'NO_EM_RESTART': '1', 19:22:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:13 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:13 INFO - 'SHELL': '/bin/bash', 19:22:13 INFO - 'SHLVL': '1', 19:22:13 INFO - 'TERM': 'linux', 19:22:13 INFO - 'TMOUT': '86400', 19:22:13 INFO - 'USER': 'cltbld', 19:22:13 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:13 INFO - '_': '/tools/buildbot/bin/python'} 19:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 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)) 19:22:13 INFO - Cleaning up... 19:22:13 INFO - Return code: 0 19:22:13 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:22:13 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')]} 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:13 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')]} 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:13 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:13 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 19:22:13 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 19:22:13 INFO - Using env: {'DISPLAY': ':0', 19:22:13 INFO - 'HOME': '/home/cltbld', 19:22:13 INFO - 'LANG': 'en_US.UTF-8', 19:22:13 INFO - 'LANGUAGE': 'en_US:en', 19:22:13 INFO - 'LOGNAME': 'cltbld', 19:22:13 INFO - 'MAIL': '/var/mail/cltbld', 19:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:13 INFO - 'MOZ_NO_REMOTE': '1', 19:22:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:13 INFO - 'NO_EM_RESTART': '1', 19:22:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:13 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:13 INFO - 'SHELL': '/bin/bash', 19:22:13 INFO - 'SHLVL': '1', 19:22:13 INFO - 'TERM': 'linux', 19:22:13 INFO - 'TMOUT': '86400', 19:22:13 INFO - 'USER': 'cltbld', 19:22:13 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:13 INFO - '_': '/tools/buildbot/bin/python'} 19:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:13 INFO - Downloading/unpacking jsonschema 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:22:15 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 19:22:15 INFO - Downloading/unpacking functools32 (from jsonschema) 19:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:15 INFO - Downloading functools32-3.2.3-2.tar.gz 19:22:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 19:22:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:22:15 INFO - warning: no files found matching '*.txt' 19:22:15 INFO - no previously-included directories found matching 'build' 19:22:15 INFO - no previously-included directories found matching 'dist' 19:22:15 INFO - no previously-included directories found matching '.git*' 19:22:15 INFO - Installing collected packages: jsonschema, functools32 19:22:15 INFO - Running setup.py install for functools32 19:22:15 INFO - warning: no files found matching '*.txt' 19:22:15 INFO - no previously-included directories found matching 'build' 19:22:15 INFO - no previously-included directories found matching 'dist' 19:22:15 INFO - no previously-included directories found matching '.git*' 19:22:16 INFO - Successfully installed jsonschema functools32 19:22:16 INFO - Cleaning up... 19:22:16 INFO - Return code: 0 19:22:16 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 19:22:16 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')]} 19:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:16 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')]} 19:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:16 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 0x7f83a34a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1181600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, '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': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', '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 19:22:16 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 19:22:16 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 19:22:16 INFO - Using env: {'DISPLAY': ':0', 19:22:16 INFO - 'HOME': '/home/cltbld', 19:22:16 INFO - 'LANG': 'en_US.UTF-8', 19:22:16 INFO - 'LANGUAGE': 'en_US:en', 19:22:16 INFO - 'LOGNAME': 'cltbld', 19:22:16 INFO - 'MAIL': '/var/mail/cltbld', 19:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:16 INFO - 'MOZ_NO_REMOTE': '1', 19:22:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:16 INFO - 'NO_EM_RESTART': '1', 19:22:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:16 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:16 INFO - 'SHELL': '/bin/bash', 19:22:16 INFO - 'SHLVL': '1', 19:22:16 INFO - 'TERM': 'linux', 19:22:16 INFO - 'TMOUT': '86400', 19:22:16 INFO - 'USER': 'cltbld', 19:22:16 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:16 INFO - '_': '/tools/buildbot/bin/python'} 19:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:16 INFO - Downloading/unpacking flake8 19:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:22:17 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 19:22:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:22:18 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 19:22:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 19:22:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 19:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:22:18 INFO - no previously-included directories found matching 'docs/_build' 19:22:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:22:18 INFO - Running setup.py install for pep8 19:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:22:18 INFO - no previously-included directories found matching 'docs/_build' 19:22:18 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 19:22:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:22:18 INFO - Cleaning up... 19:22:18 INFO - Return code: 0 19:22:18 INFO - Running post-action listener: _resource_record_post_action 19:22:18 INFO - Running post-action listener: _start_resource_monitoring 19:22:18 INFO - Starting resource monitoring. 19:22:18 INFO - ##### 19:22:18 INFO - ##### Running install step. 19:22:18 INFO - ##### 19:22:18 INFO - Running pre-action listener: _resource_record_pre_action 19:22:18 INFO - Running main action method: install 19:22:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:22:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:22:19 INFO - Reading from file tmpfile_stdout 19:22:19 INFO - Detecting whether we're running mozinstall >=1.0... 19:22:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:22:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:22:19 INFO - Reading from file tmpfile_stdout 19:22:19 INFO - Output received: 19:22:19 INFO - Usage: mozinstall [options] installer 19:22:19 INFO - Options: 19:22:19 INFO - -h, --help show this help message and exit 19:22:19 INFO - -d DEST, --destination=DEST 19:22:19 INFO - Directory to install application into. [default: 19:22:19 INFO - "/builds/slave/test-pgo"] 19:22:19 INFO - --app=APP Application being installed. [default: firefox] 19:22:19 INFO - mkdir: /builds/slave/test-pgo/build/application 19:22:19 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'] 19:22:19 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 19:22:33 INFO - Reading from file tmpfile_stdout 19:22:33 INFO - Output received: 19:22:33 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:22:33 INFO - Running post-action listener: _resource_record_post_action 19:22:33 INFO - ##### 19:22:33 INFO - ##### Running run-tests step. 19:22:33 INFO - ##### 19:22:33 INFO - Running pre-action listener: _resource_record_pre_action 19:22:33 INFO - Running main action method: run_tests 19:22:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:22:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:22:33 INFO - Python 2.7.3 19:22:33 INFO - Return code: 0 19:22:33 INFO - grabbing minidump binary from tooltool 19:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:33 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 0x1210350>, '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 0x1211250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12116e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:22:33 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 19:22:33 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 19:22:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:22:33 INFO - Return code: 0 19:22:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:22:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:22:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:22:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:22:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:22:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 19:22:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 19:22:34 INFO - Return code: 0 19:22:34 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/1452128716/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 19:22:34 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/1452128716/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost 19:22:34 INFO - Using env: {'DISPLAY': ':0', 19:22:34 INFO - 'HOME': '/home/cltbld', 19:22:34 INFO - 'LANG': 'en_US.UTF-8', 19:22:34 INFO - 'LANGUAGE': 'en_US:en', 19:22:34 INFO - 'LOGNAME': 'cltbld', 19:22:34 INFO - 'MAIL': '/var/mail/cltbld', 19:22:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:22:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:34 INFO - 'MOZ_NO_REMOTE': '1', 19:22:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:34 INFO - 'NO_EM_RESTART': '1', 19:22:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:22:34 INFO - 'PWD': '/builds/slave/test-pgo', 19:22:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:22:34 INFO - 'SHELL': '/bin/bash', 19:22:34 INFO - 'SHLVL': '1', 19:22:34 INFO - 'TERM': 'linux', 19:22:34 INFO - 'TMOUT': '86400', 19:22:34 INFO - 'USER': 'cltbld', 19:22:34 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837', 19:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:34 INFO - '_': '/tools/buildbot/bin/python'} 19:22:34 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/1452128716/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] with output_timeout 3600 19:22:34 INFO - mozversion INFO | application_buildid: 20160106170516 19:22:34 INFO - mozversion INFO | application_changeset: faa80a5c54b0ef9f76fa14489d0173f04dbf0be6 19:22:34 INFO - mozversion INFO | application_display_name: Nightly 19:22:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:22:34 INFO - mozversion INFO | application_name: Firefox 19:22:34 INFO - mozversion INFO | application_remotingname: firefox 19:22:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:22:34 INFO - mozversion INFO | application_vendor: Mozilla 19:22:34 INFO - mozversion INFO | application_version: 44.0 19:22:34 INFO - mozversion INFO | platform_buildid: 20160106170516 19:22:34 INFO - mozversion INFO | platform_changeset: faa80a5c54b0ef9f76fa14489d0173f04dbf0be6 19:22:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:22:34 INFO - mozversion INFO | platform_version: 44.0 19:22:34 INFO - 2016-01-06 19:22:34,571 DEBUG : using testdate: 1452136954 19:22:34 INFO - 2016-01-06 19:22:34,571 DEBUG : actual date: 1452136954 19:22:34 INFO - 2016-01-06 19:22:34,667 INFO : starting webserver on 'localhost:44889' 19:22:34 INFO - 2016-01-06 19:22:34,668 INFO : Starting test suite talos-linux64-ix-008 19:22:34 INFO - 2016-01-06 19:22:34,668 INFO : Starting test dromaeo_css 19:22:34 INFO - 2016-01-06 19:22:34,668 DEBUG : operating with platform_type : linux_ 19:22:34 INFO - 2016-01-06 19:22:34,669 INFO : Initialising browser for dromaeo_css test... 19:22:34 INFO - 2016-01-06 19:22:34,684 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjMtRpA/profile http://localhost:44889/getInfo.html 19:22:38 INFO - 2016-01-06 19:22:38,217 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:22:38 INFO - 2016-01-06 19:22:38,217 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:22:38 INFO - 2016-01-06 19:22:38,217 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:22:38 INFO - 2016-01-06 19:22:38,218 DEBUG : BROWSER_OUTPUT: __metrics 19:22:38 INFO - 2016-01-06 19:22:38,374 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] 19:22:39 INFO - 2016-01-06 19:22:39,487 INFO : Browser initialized. 19:22:39 INFO - 2016-01-06 19:22:39,488 INFO : Running cycle 1/1 for dromaeo_css test... 19:22:39 INFO - 2016-01-06 19:22:39,488 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjMtRpA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:22:50 INFO - 2016-01-06 19:22:50,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 128520192 19:22:50 INFO - 2016-01-06 19:22:50,974 DEBUG : BROWSER_OUTPUT: 19:23:41 INFO - 2016-01-06 19:23:41,762 DEBUG : BROWSER_OUTPUT: 1452137021761 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] 19:25:49 INFO - 2016-01-06 19:25:49,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44889/tests/dromaeo/cssquery-ext.html) 19:25:50 INFO - 2016-01-06 19:25:50,863 DEBUG : BROWSER_OUTPUT: RSS: Main: 216289280 19:25:50 INFO - 2016-01-06 19:25:50,864 DEBUG : BROWSER_OUTPUT: 19:28:52 INFO - 2016-01-06 19:28:52,894 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/dromaeo/cssquery-ext.html (next: http://localhost:44889/tests/dromaeo/cssquery-jquery.html) 19:28:53 INFO - 2016-01-06 19:28:53,455 DEBUG : BROWSER_OUTPUT: RSS: Main: 214892544 19:28:53 INFO - 2016-01-06 19:28:53,456 DEBUG : BROWSER_OUTPUT: 19:31:55 INFO - 2016-01-06 19:31:55,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44889/tests/dromaeo/cssquery-mootools.html) 19:31:55 INFO - 2016-01-06 19:31:55,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 207548416 19:31:55 INFO - 2016-01-06 19:31:55,733 DEBUG : BROWSER_OUTPUT: 19:34:57 INFO - 2016-01-06 19:34:57,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44889/tests/dromaeo/cssquery-prototype.html) 19:34:58 INFO - 2016-01-06 19:34:58,006 DEBUG : BROWSER_OUTPUT: RSS: Main: 193593344 19:34:58 INFO - 2016-01-06 19:34:58,006 DEBUG : BROWSER_OUTPUT: 19:38:00 INFO - 2016-01-06 19:38:00,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44889/tests/dromaeo/cssquery-yui.html) 19:38:00 INFO - 2016-01-06 19:38:00,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 194060288 19:38:00 INFO - 2016-01-06 19:38:00,815 DEBUG : BROWSER_OUTPUT: 19:41:02 INFO - 2016-01-06 19:41:02,777 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/dromaeo/cssquery-yui.html (next: http://localhost:44889/tests/dromaeo/cssquery-dojo.html) 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 239026176 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:41:03 INFO - 2016-01-06 19:41:03,036 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3233.337165880994;3361.613414457338;3383.255683749574;3383.289509203067;3401.2857299967;5534.501894829465;5545.916811247829;5558.888822223558;5565.805196818107;5585.8603534911645;5550.555955523559;5581.107022876341;5585.832425027245;5587.552995760339;5587.804426845061;4908.190148625473;5068.189089745632;5086.237064440326;5174.094533456649;5184.274201611777;5116.539511443969;5156.767945442445;5160.071187186305;5162.406323272834;5172.327597412331;4994.375703037121;5074.822381216658;5083.288339632454;5133.3326667533165;5140.460251673558;5098.84703458895;5111.2333150027425;5114.360704911886;5121.718305493197;5137.7944882204665;5086.084504789136;5094.59243260542;5152.458991805844;5157.535821776059;5169.379674439091;5220.556252718524;5266.526012658841;5312.335958005249;5334.0665383557725;5345.973270133663;5937.020391635375;5947.762089516415;5967.940320596782;5969.224000879901;5978.790742323998;5706.914396284059;5957.642541447483;5970.34326224115;5971.790987315467;5989.131575921467;5493.23094766733;5523.4200408956885;5571.052921003439;5572.192032155355;5605.495505404533;5992.790252341147;5995.220621319288;5998.670073145931;6047.758089676364;6064.878702426015;5648.5763567731765;5762.711864406847;5904.8780731660845;5915.940840591625;5918.171455996164;5922.2597175353085;6034.879302413927;6041.78853740117;6044.365341639064;6073.089036644538;5927.229460170237;5945.048792193228;5947.078202878561;5947.226860508106;5951.018082016505;5677.37548869624;5778.075507918713;5931.703414829241;5954.612950158225;5958.493528050164;4593.242115051046;4594.402727645452;4604.700694454917;4609.147307748078;4610.95389046112;4094.5086589609436;4106.958930410718;4107.424960505532;4118.732282401635;4135.421041054255;5469;5588.972055139699;5616.943830561724;5676.148577713294;5755.395683453261;6044.486218671374;6046.848828779228;6067.332593414721;6078.665673388006;6094.11635312886;5715.971420142872;5730.914036289459;5752.108423194411;5754.108113242371;5757.309122905194;5030.89938201234;5117.360329958755;5138.768755406016;5211;5231.55531779803;6123.050927106306;6139.754409823647;6146;6147;6148.354422785633;5762.740676669476;5996.070609055516;6049.758009679739;6053.697315134137;6057.818265452043;6027.095935609694;6029.457348838626;6121.908171377522;6123.478999575351;6124.136831511461;5908.7045647718305;5915.171875937286;5923.170756094237;5923.614965027259;5927.9703601481715;5809.970950145223;5955.434233747787;5992.610480318765;5996.310424301261;5998.310194327533;5753.165790960204;5808.07070868659;5816.447437493432;5822.388649191774;5851.882962340815;5808.128780682931;5844.883102338014;5849.766009359578;5850.414958504116;5854.092615644582;5553.416891226363;5612.326520817528;5660.094384898396;5745.683987380734;5763.884722305446;5831.562632802472;5881.852953676193;5890.852728681646;5895.02732049207;5904.439078287556;5749.913751293818;5792.044312688359;5798.739056742373;5800.56495762811;5802.071668533014 19:41:03 INFO - 2016-01-06 19:41:03,037 DEBUG : BROWSER_OUTPUT: |1;ext.html;575.7351618255602;607.6536374266669;614.339584946183;614.5728718540613;622.1383384013143;34554;35306.470402943945;43492.130157396874;44465.110697786025;44501.554984450144;47602.761986190024;48358.51641483584;48359;48370.75814620922;48395.27407088896;8277;8378.204070613281;9109;9268.582913768896;9273.072692730706;10911.399873006982;11008.339499630009;11153.330800151976;11162.497687604076;11183.32900025997;10859.45702714865;10894.945525272362;10957.726056848604;10973.890261097407;10980.560777568888;10891.618793342235;10974.670759877137;11000.669979900536;11010.944945275303;11020.063294619968;10667.946660266649;10833.945830270799;10836.945815270952;10850.24048316618;10857.728556786065;43619.78190109061;43674.12651746947;43712.3443148353;43907.34138987918;44016.11967760659;3603.351396748584;3718.2377612589626;3768.0768211788286;4318.287482565373;4458.799354029076;33931.32137357239;33963.490547641806;34247;34311;34342.31315373679;3861.2663593917064;3958.3072962231495;3960.4257382679352;4012.3179059559943;4020.2160578687167;315657;316640;316990;317344;433520.83239583916;56741.71629141828;75449;76345;76499.61750191325;76520.23479765242;70494.29505704877;71130.28869711237;71225.2877471229;71273.64363178151;71332.2866771336;16250.268737906823;16294.837051629569;16329.836701633069;16376.344946202256;16390.754138687927;13311.667208319675;13532.59402217935;13563.66090847717;13570.864291357157;13587.66030849217;22661;22699.205527806447;22894.42763930882;22900.4274893129;22900.770992290196;8070.273675369245;8227.053888802744;8308;8315.459495132796;8412.915870841336;1063.6436793674206;1079.5897558927713;1136.7215032317135;1173.700706319883;1189.393409361232;30880.53679194814;30967.845160774054;31443.52834707481;31479.52780708291;31886.84056579702;1137.3460260350332;1139.6182278936483;1220.993895030519;1229.1211783574786;1246.4391024039219;14087.506937257147;15469;15570.355140372743;15822;15836.52490425289;388395;390801;394920;396386;396631;59303;59327.406725933055;59330;59445;59625.40374596285;1348.7437386896459;1359.0282947692249;1492.8283247426684;1513.9167345795868;1519.1416849480229;29591.704082959757;29706.3661498611;29886.551701724926;30033.849830750707;30281;50047.24929126139;50335.74832125963;50397.49602503928;50479.49520504895;50581.241281381546;235688;236183;236263;236370;237286;33691;34162.48756268608;34301.31397371989;34698;34851.47722784211;45625.54374456213;46714.766426167655;49569.50430495649;49759.751201243766;50384.992300154525;41918;42136;42251.15497690091;42599.36100958426;43496.56503435052;41792.791036044626;41819.7909010453;42444.363334550624;42509.36235956401;42595.3610695846;40153.39769903512;41004;41378.1724365517;41525.792371037955;41582.37626435545;36711.26577468489;36998.26003479969;37401.62598373982;37821;37941.430878536295;27382.178534643994;27773.30566735767;27885.34472434052;35108;36467.088322792144 19:41:03 INFO - 2016-01-06 19:41:03,037 DEBUG : BROWSER_OUTPUT: |2;jquery.html;374.43647310797263;379.6052105809957;387.96702280306175;389.978551179685;392.68585131894486;12068.517259309618;15450;18237.81762182378;19522.70715939262;19575.314863979762;18039.63920721585;18435.907820460914;18561.814381856177;19335.806641933577;19348.806511934876;5681.573881958839;5815.534757219412;5816.040353341694;5819.505342045931;5825.8834823303505;4415.20526305265;4528.275475923853;5736.4550367715;6403.9359606403805;6633.137692100045;6324.430801227889;6520.445762110203;6560.376764207393;6589.17635295588;6589.868202635968;6320.715567799437;6345.206849143857;6346.9682651586445;6347.206599175103;6353.714082866259;5670.829875103754;5679.148127780824;5680.6591604503865;5686.601937864351;5872.7063646817915;23148;23532.764672353398;23566.7643323568;24155.396115097086;24452.26643200707;2339.543788961136;2474.6288056791445;2485.179890636086;2590.197038917923;2643.603459481074;15396.153211573359;15512.689746205013;15542.378304867792;15589.298481568356;16011.599710007227;2104.7895210478982;2125.978740212609;2155.4180371299663;2187.704659870913;2195.879226642534;73018;74819.62590187069;76138.23861761422;76779.23220767776;77343.6132819338;17217.827821721872;17482.30070797154;17531.211095500734;17594.824051759573;17654.558636033944;26608.60087098695;26661.600075998875;26711.732882670924;26981;26991.730082698923;10224.335418197794;10460;10467.267291289536;10467.947660261802;10545.103666188306;8942.910570894208;8995.730128096167;8996.100389960951;9057.954710226406;9115.225205857576;8590.699325523577;8714.825703486022;8763;8771.517566533774;8771.868421973675;1203.8495188101488;1208.8851559101872;1210.8910198082215;1223.8409006829233;2147.871127732341;503.97228152451964;506.67572753437827;509.48541972607933;533.8692020454936;535.4698848140391;10736.516856741466;11177.552897883994;11570.074394048455;12869;13093.279869607262;542.4087744358668;548.4816848078607;551.2778260478785;558.1989844573051;565.2877374508149;6654.83362915931;6715.2613212546585;6833.93166068333;6849.2808255132495;6851.109355783816;606908;607336;608518;612077;623508;78762;79099;80197.18816871007;82430.58784706038;83877.16122838815;598.2043881637499;600.129811772931;602.4999250621973;603.0833133636847;603.2429301226932;11946.880531194924;12282.447289872156;12302.938485307515;12371.69070773238;12373.381330933596;39060.80469597634;39228.607713922494;39604.60395396009;41067;41601.58398415977;51410;53327.73336133295;53600.46399535955;54448;54458.45541544692;7434.73978410753;7562.054743157106;7690.730824421118;7746.147923728498;7761.379089672925;10868.891311087104;11044.226904116635;11904.345261010727;13899.791503127259;14001.439942402598;10162.441065741456;10902.182336324966;11076.723081923017;11081.224314297922;11157.10743140563;10845.186611004365;10911.399873006743;10939;10972.890271097187;11172.664820055412;11091.389973551532;11151.275167114112;11289.943550282196;11416.372099534605;11447.427628618703;7257.310555497219;7327.890081648886;7518.28576285252;7553.131389890013;7559.3196612305155;10764.515596798006;11282.548698051985;11303.886961130283;11497.770044599229;11504.597339093092 19:41:03 INFO - 2016-01-06 19:41:03,038 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1568.4196847166547;1590.4433448293107;1629.6414788746476;1842.9262829486813;1948.3570421760812;7228.457865660071;7343.082114735658;7729.768106956793;7916.6833326666865;7916.920830791698;7698.114716807569;7795.649195786189;7884.32983196429;7887;7891.1714270001685;2360.173939121307;2432.1244352033254;2432.3311089450453;2439.9390015249674;2442.5237078769646;2151.34384012876;2189.277538412328;2388.8447250928634;2449.081594402099;2452.411421258894;2385.439421735891;2396.364963284731;2397.7841994220516;2398.796102331304;2399.448126930807;2450.8284420090513;2472.925812225636;2475.0718480569785;2475.8266921315517;2486.1422809130822;2393.114547617375;2407.3861165402886;2411.963820542693;2421.4672771990136;2428.1744206969715;7952.045754509438;8061.234182752629;8105.473144245605;8106.027276726831;8111.472754270953;5028.547430731252;5120.180771076647;5125;5126.333576635014;5130.050940575968;3246.3020450603;3264.6408895021527;3331.7001469867587;3400.0989737719524;3412.7269818414466;1155.8901904319075;1156.8900954409319;1183.7040739815047;1187.5249900039967;1195;11271.041961433288;11304;11316.943415282953;11317.264377815416;11323.037541808957;4545.36364908913;4549.249373853343;4591.150637132141;4603.125406172817;4629.560191781776;9416.858747118798;9421.764455888657;9444.8583271251;9445.386049906734;9462.337636365526;5120.539151476385;5133.8716532087;5143.048536020848;5151.407588127413;5170.1469257572835;4651.465081515669;4661.95338046622;4666.2767271073035;4667.579917807346;4669.533046695371;3707.277080969185;4067.2678917795083;4084.0402505411203;4127.442795222667;4161.0221597924465;1262.0786825617167;1266.9809952850715;1304.1197191895433;1305.9738805223842;1317.8155058291848;1297.928613926243;1408.1058527834898;1438.0940007795023;1466.992665036668;1470.2207829850197;7718.845623087507;7764.3788496920115;7809.414293927978;7858.921410785933;7861.2531809478005;1224.6632176151452;1233.2230694662305;1233.6422437493027;1238.2143017238488;1249.1006475337742;6188.969055154786;6196.225471816023;6287;6298.338674439209;6304.779332723424;9379;9587.041295870496;9593.952030239805;9604.99147589507;9611.711648650551;9364.484953327496;9517.524123793783;9539.90460095404;9543.47510886894;9543.61825526985;7561.810954726176;7656.081270247606;7660.923390766133;7662.540247585162;7665.770026899202;7295.306945840137;7375.889361659472;7402.4078073755045;7410.666520006721;7414.036175297177;8634.481931084156;8642.870356944524;8871.911280887367;8892.555372231493;8909.643614255358;8672.653093876173;8728.214460698568;8736.69421570242;8758.737237882873;9096.499692516974;7387.335139837441;7443.665035073543;7462.141853686896;7467.1039475263315;7468.402527797872;6758.526903116931;7129.7861064168155;7719.884201737091;7800.4149688774705;7853.057633084067;7024.121984751906;7204.351608355273;7240.05879235696;7254.16577093641;7443.516171448839;7311;7408.222136675571;7419.22098179705;7488.962555187407;7513.62431878393;7286.089238845145;7332.5967071811565;7361.705531778669;7408.333250007525;7408.888866666896;6511.38141876521;6581;6587.538872278895;6594.934050659432;6628.105205797156;6948.305169483011;6979.30206979298;6983.1620205575655;6991.126109236346;6994.440444764508 19:41:03 INFO - 2016-01-06 19:41:03,039 DEBUG : BROWSER_OUTPUT: |4;prototype.html;345.5469749702501;349.98600055997747;355.2965129044492;357.39778473272537;357.6548630571499;5921.703914804253;5992.700364981744;6021.0667346561295;6021.3376528581875;6034.24571928509;5833.358330583633;5938.346781854003;5946.643201407907;5953.493953013988;5978.432048955342;4634.026854360588;4666.650001249903;4671.743054132022;4671.976640116794;4672.742999135046;4653.0228651983125;4662.557057079589;4668.439787225538;4677.789499472532;4682.859514214579;4594.402727645402;4654.348391225231;4686.671932964694;4700.27145792401;4706.29405589161;4647.372604698357;4662.04428092242;4665.09030739006;4667.6965997210245;4690.671652984292;3997.6601988830985;4135.255653982282;4141.730787498803;4151.024509240327;4166.604172603597;5599.020171470017;5642.7178641067785;5740.56945729072;5746.482816546489;5752.482276595127;5337.70642614656;5365.08793505105;5381.0852155133725;5382.0312343778105;5386.8922621547745;6332.810015699536;6339.175907132089;6339.524535659844;6343.714532846033;6343.936560634427;4457.375967364572;4529.343245229424;4547.18150732868;4558.58972692456;4587.954120458786;6595.373439523238;6612.636305003271;6617.7022034008105;6619.073329733841;6624.138861947916;4697.342372067913;4705.12955103307;4710.010897711519;4710.269908164171;4710.36410084641;4628.051249493846;4693.131770622377;4767.046590681808;4768.213244814636;4771.809127634856;5358.356997160366;5367.033933891937;5370.436104209002;5371.221172929903;5371.811986580451;6282.685865706696;6299.96850015747;6332.398422149889;6337.841553961188;6338.7464501420345;1714.202895653502;1751.1331890714177;1751.6321572469924;1753.3512600337956;1753.4739578126766;1163.0521125282867;1169.0881112732084;1173.565780661161;1180.0677464802882;1193.964181074569;6203.069539569101;6210.223722034746;6214.161088253119;6214.40963108504;6217.813465596039;6537.705803238866;6561.475081993429;6605.735770569219;6606.339366063356;6607.273199948002;6078.635281883101;6166.321704612489;6213.565050446516;6216.533759968021;6220.937790622036;3620.456931460302;3649.94525082124;3654.086478380405;3655.597884232732;3656.7074634029213;6493.090967264587;6591.769288074895;6652.90020649681;6726.39462448382;6766.72933082681;6724.798256052326;6748.021536877223;6765.526413151032;6769.018492318586;6774.525783195228;6675.566088204251;6721.059051732761;6733.595984240961;6735.6295403753265;6740.022696708949;6439.935600643934;6642.800715978335;6651.0355998379;6655.966720166368;6660.533762636763;6596.967015164893;6608.4052435281055;6613.570117942319;6613.867722645617;6618.569792963442;6815.318468153145;6838.897416538656;6846.5892046477375;6846.897296540456;6853.862922741617;4660.673752837269;4829.710217386968;4833.419989601271;4835.2505361669;4838.491958344464;6536.509761767981;6582.144321238208;6591.505637077143;6593.340665933369;6596.67016649156;6417.935820641734;6479.092926990131;6479.384458476437;6480.351964803671;6481.773137940149;6302.747890084345;6438.323975982455;6462.289148193789;6466.838329041623;6471.191101112361;6357.586756860975;6360;6362.681865906594;6364.459020983269;6368.23581170244;6364.840878978062;6434.453071488976;6497.02544618311;6497.74009039652;6500.3174666659315;6333.081703153109;6344.873102537831;6346.587471814318;6346.777862774781;6349.301576826453 19:41:03 INFO - 2016-01-06 19:41:03,040 DEBUG : BROWSER_OUTPUT: |5;yui.html;853.0914575976584;907.1246247371286;949.9335046546743;955.3169483819067;975.5366201054495;15400.460983865567;15613.765793513077;15680.372785088583;15710.92144539277;15743.448979285728;14647.853521464782;14702.264886755645;14785.038972466782;14798.704025919475;14808.407663693439;773.1109224391952;786.2334224131483;936.5223735894675;957.0525180071713;959.6065613098631;906.9410488318272;945.1021529546923;949.103097572794;953.8378475659155;954.9618015279415;816.7019038051128;954.4988880837581;983.2920297385871;985.3201291109148;985.571276494727;802.7471346525855;970.1898914406444;988;988.0415996483399;989.3272574649236;715.9463040271949;733.8238822682571;743.6542007966322;743.8661041012616;744.7616762635959;14393.71212575753;14549.636259093502;14592.270386480739;14612.050216735877;14616.196109213988;13468.326583670778;13832.30838458083;13975.161490310615;14112.576622701336;14160.221187834664;11242.550297988071;11365.31808091517;11520;11591.362475063868;11658.59194928182;3715.498407714951;3847.615238476158;3947.7433966792355;3948.3879998600264;3977.2244412339605;50957.235641465406;51307.74346128283;56027.43972560263;56100.71949640226;56144.71927640377;11600.88399115998;11770;11872.228305160137;11901.821472677917;12050.638480845588;21344.466388340417;21380.572388552453;21381.46546336354;21387.8930605346;21457.67813482799;8719.34604904622;8832;8835.823283534293;8911;9206.26349892007;4589.977050114795;4812.133815913099;4816.20532612122;4817.325574419584;4914.533119353656;3280.557124788123;3305.388503126929;3307.851146698404;3309.619393769699;3310.2055506678225;498.9201727723547;519.4831143012651;520.0976306108898;523.1551045062273;524.4283730733518;638.565775272819;654.7511945460694;680.6052489556145;706.7243774927782;753.5290443472829;8834.64661413549;9396.107369799858;9560.521973901416;9575.760605984906;9871.703848884545;677.2144312597363;705.4497491956282;717.3902183144286;743.353282644103;763.4846478626905;4421.668374871833;4519.593236608721;4649.67452278344;4665.066986602693;4680.789364478538;67473.32526674769;68375;69102.65448672725;69358.30641693619;69399;59756.402435975084;60801.695991520646;61229.69385153046;61450.38549614447;61495.69252153711;50253.748731256106;50877.745611271705;51667.74166129145;52238.73880630572;53173;9036.051214622634;9258.444493330422;9605.567749451431;9674.467904265051;9737.80524389494;40189.59810401859;40768.3884741735;40939.59060409477;41879.162416750885;42166.789166053975;46892.29661555011;49463.25805112854;49720.502794973036;49864;50270.24594631157;9076.954615226881;9211.124943130391;9422.199113075465;9472.810543788948;9740.61037558518;13303.534376296782;13702.10936289138;13856.30718464093;13951.511697090553;13958.23229722375;11132.833007505056;11572.768544629229;11882.28706277626;12016.098792590416;12108.75782484363;12285.262884226975;12991.610251692464;13181.406836692564;13409.66475838112;13459.125156865164;10976.23166378346;11014.834777478183;11091.112710983263;11093.44532773342;11529.308241505536;11122.388268645302;11467.655970320904;11574.942125289319;11579.710507237387;12084.939575302067;10306.381617102998;10393.37639741618;10404.895951040393;10678.359298442118;10782.406967616856 19:41:03 INFO - 2016-01-06 19:41:03,040 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:41:03 INFO - 2016-01-06 19:41:03,040 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:41:03 INFO - 2016-01-06 19:41:03,040 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,717 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: __startTimestamp1452138063032__endTimestamp 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5540.17 Median:5755.40 stddev:602.78 (10.5%) stddev-sans-first:576.82 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: Values: 3233.3 3361.6 3383.3 3383.3 3401.3 5534.5 5545.9 5558.9 5565.8 5585.9 5550.6 5581.1 5585.8 5587.6 5587.8 4908.2 5068.2 5086.2 5174.1 5184.3 5116.5 5156.8 5160.1 5162.4 5172.3 4994.4 5074.8 5083.3 5133.3 5140.5 5098.8 5111.2 5114.4 5121.7 5137.8 5086.1 5094.6 5152.5 5157.5 5169.4 5220.6 5266.5 5312.3 5334.1 5346.0 5937.0 5947.8 5967.9 5969.2 5978.8 5706.9 5957.6 5970.3 5971.8 5989.1 5493.2 5523.4 5571.1 5572.2 5605.5 5992.8 5995.2 5998.7 6047.8 6064.9 5648.6 5762.7 5904.9 5915.9 5918.2 5922.3 6034.9 6041.8 6044.4 6073.1 5927.2 5945.0 5947.1 5947.2 5951.0 5677.4 5778.1 5931.7 5954.6 5958.5 4593.2 4594.4 4604.7 4609.1 4611.0 4094.5 4107.0 4107.4 4118.7 4135.4 5469.0 5589.0 5616.9 5676.1 5755.4 6044.5 6046.8 6067.3 6078.7 6094.1 5716.0 5730.9 5752.1 5754.1 5757.3 5030.9 5117.4 5138.8 5211.0 5231.6 6123.1 6139.8 6146.0 6147.0 6148.4 5762.7 5996.1 6049.8 6053.7 6057.8 6027.1 6029.5 6121.9 6123.5 6124.1 5908.7 5915.2 5923.2 5923.6 5928.0 5810.0 5955.4 5992.6 5996.3 5998.3 5753.2 5808.1 5816.4 5822.4 5851.9 5808.1 5844.9 5849.8 5850.4 5854.1 5553.4 5612.3 5660.1 5745.7 5763.9 5831.6 5881.9 5890.9 5895.0 5904.4 5749.9 5792.0 5798.7 5800.6 5802.1 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51916.58 Median:20035.22 stddev:86817.29 (433.3%) stddev-sans-first:86975.26 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: Values: 575.7 607.7 614.3 614.6 622.1 34554.0 35306.5 43492.1 44465.1 44501.6 47602.8 48358.5 48359.0 48370.8 48395.3 8277.0 8378.2 9109.0 9268.6 9273.1 10911.4 11008.3 11153.3 11162.5 11183.3 10859.5 10894.9 10957.7 10973.9 10980.6 10891.6 10974.7 11000.7 11010.9 11020.1 10667.9 10833.9 10836.9 10850.2 10857.7 43619.8 43674.1 43712.3 43907.3 44016.1 3603.4 3718.2 3768.1 4318.3 4458.8 33931.3 33963.5 34247.0 34311.0 34342.3 3861.3 3958.3 3960.4 4012.3 4020.2 315657.0 316640.0 316990.0 317344.0 433520.8 56741.7 75449.0 76345.0 76499.6 76520.2 70494.3 71130.3 71225.3 71273.6 71332.3 16250.3 16294.8 16329.8 16376.3 16390.8 13311.7 13532.6 13563.7 13570.9 13587.7 22661.0 22699.2 22894.4 22900.4 22900.8 8070.3 8227.1 8308.0 8315.5 8412.9 1063.6 1079.6 1136.7 1173.7 1189.4 30880.5 30967.8 31443.5 31479.5 31886.8 1137.3 1139.6 1221.0 1229.1 1246.4 14087.5 15469.0 15570.4 15822.0 15836.5 388395.0 390801.0 394920.0 396386.0 396631.0 59303.0 59327.4 59330.0 59445.0 59625.4 1348.7 1359.0 1492.8 1513.9 1519.1 29591.7 29706.4 29886.6 30033.8 30281.0 50047.2 50335.7 50397.5 50479.5 50581.2 235688.0 236183.0 236263.0 236370.0 237286.0 33691.0 34162.5 34301.3 34698.0 34851.5 45625.5 46714.8 49569.5 49759.8 50385.0 41918.0 42136.0 42251.2 42599.4 43496.6 41792.8 41819.8 42444.4 42509.4 42595.4 40153.4 41004.0 41378.2 41525.8 41582.4 36711.3 36998.3 37401.6 37821.0 37941.4 27382.2 27773.3 27885.3 35108.0 36467.1 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,041 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31946.11 Median:19810.65 stddev:100029.66 (504.9%) stddev-sans-first:100282.17 19:41:03 INFO - 2016-01-06 19:41:03,043 DEBUG : BROWSER_OUTPUT: Values: 374.4 379.6 388.0 390.0 392.7 12068.5 15450.0 18237.8 19522.7 19575.3 18039.6 18435.9 18561.8 19335.8 19348.8 5681.6 5815.5 5816.0 5819.5 5825.9 4415.2 4528.3 5736.5 6403.9 6633.1 6324.4 6520.4 6560.4 6589.2 6589.9 6320.7 6345.2 6347.0 6347.2 6353.7 5670.8 5679.1 5680.7 5686.6 5872.7 23148.0 23532.8 23566.8 24155.4 24452.3 2339.5 2474.6 2485.2 2590.2 2643.6 15396.2 15512.7 15542.4 15589.3 16011.6 2104.8 2126.0 2155.4 2187.7 2195.9 73018.0 74819.6 76138.2 76779.2 77343.6 17217.8 17482.3 17531.2 17594.8 17654.6 26608.6 26661.6 26711.7 26981.0 26991.7 10224.3 10460.0 10467.3 10467.9 10545.1 8942.9 8995.7 8996.1 9058.0 9115.2 8590.7 8714.8 8763.0 8771.5 8771.9 1203.8 1208.9 1210.9 1223.8 2147.9 504.0 506.7 509.5 533.9 535.5 10736.5 11177.6 11570.1 12869.0 13093.3 542.4 548.5 551.3 558.2 565.3 6654.8 6715.3 6833.9 6849.3 6851.1 606908.0 607336.0 608518.0 612077.0 623508.0 78762.0 79099.0 80197.2 82430.6 83877.2 598.2 600.1 602.5 603.1 603.2 11946.9 12282.4 12302.9 12371.7 12373.4 39060.8 39228.6 39604.6 41067.0 41601.6 51410.0 53327.7 53600.5 54448.0 54458.5 7434.7 7562.1 7690.7 7746.1 7761.4 10868.9 11044.2 11904.3 13899.8 14001.4 10162.4 10902.2 11076.7 11081.2 11157.1 10845.2 10911.4 10939.0 10972.9 11172.7 11091.4 11151.3 11289.9 11416.4 11447.4 7257.3 7327.9 7518.3 7553.1 7559.3 10764.5 11282.5 11303.9 11497.8 11504.6 19:41:03 INFO - 2016-01-06 19:41:03,043 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,043 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5727.61 Median:6546.19 stddev:2874.56 (43.9%) stddev-sans-first:2865.62 19:41:03 INFO - 2016-01-06 19:41:03,043 DEBUG : BROWSER_OUTPUT: Values: 1568.4 1590.4 1629.6 1842.9 1948.4 7228.5 7343.1 7729.8 7916.7 7916.9 7698.1 7795.6 7884.3 7887.0 7891.2 2360.2 2432.1 2432.3 2439.9 2442.5 2151.3 2189.3 2388.8 2449.1 2452.4 2385.4 2396.4 2397.8 2398.8 2399.4 2450.8 2472.9 2475.1 2475.8 2486.1 2393.1 2407.4 2412.0 2421.5 2428.2 7952.0 8061.2 8105.5 8106.0 8111.5 5028.5 5120.2 5125.0 5126.3 5130.1 3246.3 3264.6 3331.7 3400.1 3412.7 1155.9 1156.9 1183.7 1187.5 1195.0 11271.0 11304.0 11316.9 11317.3 11323.0 4545.4 4549.2 4591.2 4603.1 4629.6 9416.9 9421.8 9444.9 9445.4 9462.3 5120.5 5133.9 5143.0 5151.4 5170.1 4651.5 4662.0 4666.3 4667.6 4669.5 3707.3 4067.3 4084.0 4127.4 4161.0 1262.1 1267.0 1304.1 1306.0 1317.8 1297.9 1408.1 1438.1 1467.0 1470.2 7718.8 7764.4 7809.4 7858.9 7861.3 1224.7 1233.2 1233.6 1238.2 1249.1 6189.0 6196.2 6287.0 6298.3 6304.8 9379.0 9587.0 9594.0 9605.0 9611.7 9364.5 9517.5 9539.9 9543.5 9543.6 7561.8 7656.1 7660.9 7662.5 7665.8 7295.3 7375.9 7402.4 7410.7 7414.0 8634.5 8642.9 8871.9 8892.6 8909.6 8672.7 8728.2 8736.7 8758.7 9096.5 7387.3 7443.7 7462.1 7467.1 7468.4 6758.5 7129.8 7719.9 7800.4 7853.1 7024.1 7204.4 7240.1 7254.2 7443.5 7311.0 7408.2 7419.2 7489.0 7513.6 7286.1 7332.6 7361.7 7408.3 7408.9 6511.4 6581.0 6587.5 6594.9 6628.1 6948.3 6979.3 6983.2 6991.1 6994.4 19:41:03 INFO - 2016-01-06 19:41:03,043 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,043 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5386.40 Median:6122.48 stddev:1575.79 (25.7%) stddev-sans-first:1534.12 19:41:03 INFO - 2016-01-06 19:41:03,044 DEBUG : BROWSER_OUTPUT: Values: 345.5 350.0 355.3 357.4 357.7 5921.7 5992.7 6021.1 6021.3 6034.2 5833.4 5938.3 5946.6 5953.5 5978.4 4634.0 4666.7 4671.7 4672.0 4672.7 4653.0 4662.6 4668.4 4677.8 4682.9 4594.4 4654.3 4686.7 4700.3 4706.3 4647.4 4662.0 4665.1 4667.7 4690.7 3997.7 4135.3 4141.7 4151.0 4166.6 5599.0 5642.7 5740.6 5746.5 5752.5 5337.7 5365.1 5381.1 5382.0 5386.9 6332.8 6339.2 6339.5 6343.7 6343.9 4457.4 4529.3 4547.2 4558.6 4588.0 6595.4 6612.6 6617.7 6619.1 6624.1 4697.3 4705.1 4710.0 4710.3 4710.4 4628.1 4693.1 4767.0 4768.2 4771.8 5358.4 5367.0 5370.4 5371.2 5371.8 6282.7 6300.0 6332.4 6337.8 6338.7 1714.2 1751.1 1751.6 1753.4 1753.5 1163.1 1169.1 1173.6 1180.1 1194.0 6203.1 6210.2 6214.2 6214.4 6217.8 6537.7 6561.5 6605.7 6606.3 6607.3 6078.6 6166.3 6213.6 6216.5 6220.9 3620.5 3649.9 3654.1 3655.6 3656.7 6493.1 6591.8 6652.9 6726.4 6766.7 6724.8 6748.0 6765.5 6769.0 6774.5 6675.6 6721.1 6733.6 6735.6 6740.0 6439.9 6642.8 6651.0 6656.0 6660.5 6597.0 6608.4 6613.6 6613.9 6618.6 6815.3 6838.9 6846.6 6846.9 6853.9 4660.7 4829.7 4833.4 4835.3 4838.5 6536.5 6582.1 6591.5 6593.3 6596.7 6417.9 6479.1 6479.4 6480.4 6481.8 6302.7 6438.3 6462.3 6466.8 6471.2 6357.6 6360.0 6362.7 6364.5 6368.2 6364.8 6434.5 6497.0 6497.7 6500.3 6333.1 6344.9 6346.6 6346.8 6349.3 19:41:03 INFO - 2016-01-06 19:41:03,044 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,045 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15618.55 Median:27188.90 stddev:18512.97 (68.1%) stddev-sans-first:18531.69 19:41:03 INFO - 2016-01-06 19:41:03,045 DEBUG : BROWSER_OUTPUT: Values: 853.1 907.1 949.9 955.3 975.5 15400.5 15613.8 15680.4 15710.9 15743.4 14647.9 14702.3 14785.0 14798.7 14808.4 773.1 786.2 936.5 957.1 959.6 906.9 945.1 949.1 953.8 955.0 816.7 954.5 983.3 985.3 985.6 802.7 970.2 988.0 988.0 989.3 715.9 733.8 743.7 743.9 744.8 14393.7 14549.6 14592.3 14612.1 14616.2 13468.3 13832.3 13975.2 14112.6 14160.2 11242.6 11365.3 11520.0 11591.4 11658.6 3715.5 3847.6 3947.7 3948.4 3977.2 50957.2 51307.7 56027.4 56100.7 56144.7 11600.9 11770.0 11872.2 11901.8 12050.6 21344.5 21380.6 21381.5 21387.9 21457.7 8719.3 8832.0 8835.8 8911.0 9206.3 4590.0 4812.1 4816.2 4817.3 4914.5 3280.6 3305.4 3307.9 3309.6 3310.2 498.9 519.5 520.1 523.2 524.4 638.6 654.8 680.6 706.7 753.5 8834.6 9396.1 9560.5 9575.8 9871.7 677.2 705.4 717.4 743.4 763.5 4421.7 4519.6 4649.7 4665.1 4680.8 67473.3 68375.0 69102.7 69358.3 69399.0 59756.4 60801.7 61229.7 61450.4 61495.7 50253.7 50877.7 51667.7 52238.7 53173.0 9036.1 9258.4 9605.6 9674.5 9737.8 40189.6 40768.4 40939.6 41879.2 42166.8 46892.3 49463.3 49720.5 49864.0 50270.2 9077.0 9211.1 9422.2 9472.8 9740.6 13303.5 13702.1 13856.3 13951.5 13958.2 11132.8 11572.8 11882.3 12016.1 12108.8 12285.3 12991.6 13181.4 13409.7 13459.1 10976.2 11014.8 11091.1 11093.4 11529.3 11122.4 11467.7 11574.9 11579.7 12084.9 10306.4 10393.4 10404.9 10678.4 10782.4 19:41:03 INFO - 2016-01-06 19:41:03,045 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:41:03 INFO - 2016-01-06 19:41:03,045 DEBUG : BROWSER_OUTPUT: 19:41:03 INFO - 2016-01-06 19:41:03,631 INFO : Browser exited with error code: 0 19:41:03 INFO - 2016-01-06 19:41:03,652 INFO : Completed test dromaeo_css (00:18:28) 19:41:03 INFO - 2016-01-06 19:41:03,652 INFO : Starting test kraken 19:41:03 INFO - 2016-01-06 19:41:03,652 DEBUG : operating with platform_type : linux_ 19:41:03 INFO - 2016-01-06 19:41:03,653 INFO : Initialising browser for kraken test... 19:41:03 INFO - 2016-01-06 19:41:03,659 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpua4KCr/profile http://localhost:44889/getInfo.html 19:41:07 INFO - 2016-01-06 19:41:07,023 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:41:07 INFO - 2016-01-06 19:41:07,023 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:41:07 INFO - 2016-01-06 19:41:07,023 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:41:07 INFO - 2016-01-06 19:41:07,023 DEBUG : BROWSER_OUTPUT: __metrics 19:41:07 INFO - 2016-01-06 19:41:07,107 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] 19:41:08 INFO - 2016-01-06 19:41:08,299 INFO : Browser initialized. 19:41:08 INFO - 2016-01-06 19:41:08,300 INFO : Running cycle 1/1 for kraken test... 19:41:08 INFO - 2016-01-06 19:41:08,300 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpua4KCr/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:41:19 INFO - 2016-01-06 19:41:19,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 128446464 19:41:19 INFO - 2016-01-06 19:41:19,861 DEBUG : BROWSER_OUTPUT: 19:41:22 INFO - 2016-01-06 19:41:22,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44889/tests/kraken/driver.html?testName=audio-beat-detection) 19:41:22 INFO - 2016-01-06 19:41:22,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 165314560 19:41:22 INFO - 2016-01-06 19:41:22,857 DEBUG : BROWSER_OUTPUT: 19:41:25 INFO - 2016-01-06 19:41:25,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44889/tests/kraken/driver.html?testName=audio-dft) 19:41:26 INFO - 2016-01-06 19:41:26,011 DEBUG : BROWSER_OUTPUT: RSS: Main: 162463744 19:41:26 INFO - 2016-01-06 19:41:26,012 DEBUG : BROWSER_OUTPUT: 19:41:28 INFO - 2016-01-06 19:41:28,793 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44889/tests/kraken/driver.html?testName=audio-fft) 19:41:29 INFO - 2016-01-06 19:41:29,355 DEBUG : BROWSER_OUTPUT: RSS: Main: 158240768 19:41:29 INFO - 2016-01-06 19:41:29,355 DEBUG : BROWSER_OUTPUT: 19:41:31 INFO - 2016-01-06 19:41:31,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44889/tests/kraken/driver.html?testName=audio-oscillator) 19:41:31 INFO - 2016-01-06 19:41:31,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 158384128 19:41:31 INFO - 2016-01-06 19:41:31,857 DEBUG : BROWSER_OUTPUT: 19:41:33 INFO - 2016-01-06 19:41:33,955 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44889/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:41:34 INFO - 2016-01-06 19:41:34,516 DEBUG : BROWSER_OUTPUT: RSS: Main: 157925376 19:41:34 INFO - 2016-01-06 19:41:34,516 DEBUG : BROWSER_OUTPUT: 19:41:37 INFO - 2016-01-06 19:41:37,399 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44889/tests/kraken/driver.html?testName=imaging-darkroom) 19:41:37 INFO - 2016-01-06 19:41:37,965 DEBUG : BROWSER_OUTPUT: RSS: Main: 157011968 19:41:37 INFO - 2016-01-06 19:41:37,965 DEBUG : BROWSER_OUTPUT: 19:41:41 INFO - 2016-01-06 19:41:41,327 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44889/tests/kraken/driver.html?testName=imaging-desaturate) 19:41:41 INFO - 2016-01-06 19:41:41,901 DEBUG : BROWSER_OUTPUT: RSS: Main: 159006720 19:41:41 INFO - 2016-01-06 19:41:41,901 DEBUG : BROWSER_OUTPUT: 19:41:44 INFO - 2016-01-06 19:41:44,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44889/tests/kraken/driver.html?testName=json-parse-financial) 19:41:45 INFO - 2016-01-06 19:41:45,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 158068736 19:41:45 INFO - 2016-01-06 19:41:45,205 DEBUG : BROWSER_OUTPUT: 19:41:46 INFO - 2016-01-06 19:41:46,714 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44889/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:41:47 INFO - 2016-01-06 19:41:47,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 161599488 19:41:47 INFO - 2016-01-06 19:41:47,264 DEBUG : BROWSER_OUTPUT: 19:41:48 INFO - 2016-01-06 19:41:48,981 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:41:49 INFO - 2016-01-06 19:41:49,567 DEBUG : BROWSER_OUTPUT: RSS: Main: 161116160 19:41:49 INFO - 2016-01-06 19:41:49,567 DEBUG : BROWSER_OUTPUT: 19:41:51 INFO - 2016-01-06 19:41:51,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:41:52 INFO - 2016-01-06 19:41:52,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 165687296 19:41:52 INFO - 2016-01-06 19:41:52,113 DEBUG : BROWSER_OUTPUT: 19:41:54 INFO - 2016-01-06 19:41:54,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:41:55 INFO - 2016-01-06 19:41:55,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 167686144 19:41:55 INFO - 2016-01-06 19:41:55,014 DEBUG : BROWSER_OUTPUT: 19:41:57 INFO - 2016-01-06 19:41:57,393 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:41:57 INFO - 2016-01-06 19:41:57,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 167469056 19:41:57 INFO - 2016-01-06 19:41:57,974 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44889/tests/kraken/driver.html?testName=ai-astar) 19:41:59 INFO - 2016-01-06 19:41:59,874 DEBUG : BROWSER_OUTPUT: RSS: Main: 224194560 19:41:59 INFO - 2016-01-06 19:41:59,874 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,875 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:41:59 INFO - 2016-01-06 19:41:59,876 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:41:59 INFO - 2016-01-06 19:41:59,876 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:41:59 INFO - 2016-01-06 19:41:59,876 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:41:59 INFO - 2016-01-06 19:41:59,876 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;103;110;109;110;98;109;105;108 19:41:59 INFO - 2016-01-06 19:41:59,877 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;120;155;160;128;147;145;123;159;126 19:41:59 INFO - 2016-01-06 19:41:59,877 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;161;160;159;161;160;160;162;159 19:41:59 INFO - 2016-01-06 19:41:59,877 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;69;148;72;72;71;71;156;71 19:41:59 INFO - 2016-01-06 19:41:59,877 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;88;88;134;88;89;88;142;88;88;131 19:41:59 INFO - 2016-01-06 19:41:59,878 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 19:41:59 INFO - 2016-01-06 19:41:59,878 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;145;145;145;145;145;145;145;146 19:41:59 INFO - 2016-01-06 19:41:59,878 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;96;95;96;96;95 19:41:59 INFO - 2016-01-06 19:41:59,879 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;52;54;55;55;58;55;55;55;52;52 19:41:59 INFO - 2016-01-06 19:41:59,879 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;63;63;62;62;62;63;62;63;62 19:41:59 INFO - 2016-01-06 19:41:59,879 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;76;78;77;77;78;77;85;80;78 19:41:59 INFO - 2016-01-06 19:41:59,879 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;108;115;113;105;120;107;117;117;115;110 19:41:59 INFO - 2016-01-06 19:41:59,879 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;137;135;134;136;135;135;134;136;134 19:41:59 INFO - 2016-01-06 19:41:59,879 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;64;64;65;65;62;65;62;63;64 19:41:59 INFO - 2016-01-06 19:41:59,880 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:41:59 INFO - 2016-01-06 19:41:59,880 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:41:59 INFO - 2016-01-06 19:41:59,880 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,882 19:41:59 INFO - 2016-01-06 19:41:59,880 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:41:59 INFO - 2016-01-06 19:41:59,880 DEBUG : BROWSER_OUTPUT: __startTimestamp1452138119875__endTimestamp 19:41:59 INFO - 2016-01-06 19:41:59,880 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,881 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.90 Median:109.00 stddev:3.84 (3.5%) stddev-sans-first:4.06 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 103.0 110.0 109.0 110.0 98.0 109.0 105.0 108.0 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.20 Median:148.00 stddev:15.48 (10.5%) stddev-sans-first:16.16 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: Values: 149.0 120.0 155.0 160.0 128.0 147.0 145.0 123.0 159.0 126.0 19:41:59 INFO - 2016-01-06 19:41:59,882 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,883 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 161.0 160.0 159.0 161.0 160.0 160.0 162.0 159.0 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.10 Median:71.00 stddev:34.27 (48.3%) stddev-sans-first:35.85 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 69.0 148.0 72.0 72.0 71.0 71.0 156.0 71.0 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.40 Median:88.00 stddev:23.11 (26.3%) stddev-sans-first:23.92 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: Values: 88.0 88.0 134.0 88.0 89.0 88.0 142.0 88.0 88.0 131.0 19:41:59 INFO - 2016-01-06 19:41:59,884 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,885 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 19:41:59 INFO - 2016-01-06 19:41:59,885 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 19:41:59 INFO - 2016-01-06 19:41:59,885 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,885 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 19:41:59 INFO - 2016-01-06 19:41:59,885 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 19:41:59 INFO - 2016-01-06 19:41:59,885 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:1.89 (3.4%) stddev-sans-first:1.81 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: Values: 52.0 54.0 55.0 55.0 58.0 55.0 55.0 55.0 52.0 52.0 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,886 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 63.0 62.0 62.0 62.0 63.0 62.0 63.0 62.0 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:78.00 stddev:3.49 (4.5%) stddev-sans-first:2.70 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: Values: 86.0 76.0 78.0 77.0 77.0 78.0 77.0 85.0 80.0 78.0 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.70 Median:115.00 stddev:4.97 (4.3%) stddev-sans-first:4.97 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: Values: 108.0 115.0 113.0 105.0 120.0 107.0 117.0 117.0 115.0 110.0 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,887 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.10 Median:135.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 19:41:59 INFO - 2016-01-06 19:41:59,888 DEBUG : BROWSER_OUTPUT: Values: 135.0 137.0 135.0 134.0 136.0 135.0 135.0 134.0 136.0 134.0 19:41:59 INFO - 2016-01-06 19:41:59,888 DEBUG : BROWSER_OUTPUT: 19:41:59 INFO - 2016-01-06 19:41:59,888 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.16 (1.8%) stddev-sans-first:1.20 19:41:59 INFO - 2016-01-06 19:41:59,888 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 65.0 65.0 62.0 65.0 62.0 63.0 64.0 19:41:59 INFO - 2016-01-06 19:41:59,888 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:41:59 INFO - 2016-01-06 19:41:59,888 DEBUG : BROWSER_OUTPUT: 19:42:00 INFO - 2016-01-06 19:42:00,523 INFO : Browser exited with error code: 0 19:42:00 INFO - 2016-01-06 19:42:00,579 INFO : Completed test kraken (00:00:56) 19:42:00 INFO - 2016-01-06 19:42:00,579 INFO : Starting test v8_7 19:42:00 INFO - 2016-01-06 19:42:00,579 DEBUG : operating with platform_type : linux_ 19:42:00 INFO - 2016-01-06 19:42:00,579 INFO : Initialising browser for v8_7 test... 19:42:00 INFO - 2016-01-06 19:42:00,585 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTwNufl/profile http://localhost:44889/getInfo.html 19:42:03 INFO - 2016-01-06 19:42:03,920 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:42:03 INFO - 2016-01-06 19:42:03,920 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:42:03 INFO - 2016-01-06 19:42:03,920 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:42:03 INFO - 2016-01-06 19:42:03,920 DEBUG : BROWSER_OUTPUT: __metrics 19:42:03 INFO - 2016-01-06 19:42:03,996 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] 19:42:05 INFO - 2016-01-06 19:42:05,151 INFO : Browser initialized. 19:42:05 INFO - 2016-01-06 19:42:05,152 INFO : Running cycle 1/1 for v8_7 test... 19:42:05 INFO - 2016-01-06 19:42:05,152 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTwNufl/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:42:16 INFO - 2016-01-06 19:42:16,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 128880640 19:42:16 INFO - 2016-01-06 19:42:16,696 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,068 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44889/tests/v8_7/run.html (next: http://localhost:44889/tests/v8_7/run.html) 19:42:38 INFO - 2016-01-06 19:42:38,312 DEBUG : BROWSER_OUTPUT: RSS: Main: 426094592 19:42:38 INFO - 2016-01-06 19:42:38,312 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,313 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:42:38 INFO - 2016-01-06 19:42:38,313 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:42:38 INFO - 2016-01-06 19:42:38,313 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |0;Richards;162.46953696181967 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.2127659574468 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.17364600781684 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5035.175879396985 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |4;RayTrace;945.179584120983 19:42:38 INFO - 2016-01-06 19:42:38,314 DEBUG : BROWSER_OUTPUT: |5;Earley;556.7928730512249 19:42:38 INFO - 2016-01-06 19:42:38,315 DEBUG : BROWSER_OUTPUT: |6;Boyer;10458.333333333334 19:42:38 INFO - 2016-01-06 19:42:38,315 DEBUG : BROWSER_OUTPUT: |7;RegExp;23627.906976744187 19:42:38 INFO - 2016-01-06 19:42:38,315 DEBUG : BROWSER_OUTPUT: |8;Splay;541.9806243272336 19:42:38 INFO - 2016-01-06 19:42:38,315 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 19:42:38 INFO - 2016-01-06 19:42:38,315 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:42:38 INFO - 2016-01-06 19:42:38,316 DEBUG : BROWSER_OUTPUT: __startTimestamp1452138158311__endTimestamp 19:42:38 INFO - 2016-01-06 19:42:38,316 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,316 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:42:38 INFO - 2016-01-06 19:42:38,316 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:42:38 INFO - 2016-01-06 19:42:38,316 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,316 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.47 Median:162.47 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,317 DEBUG : BROWSER_OUTPUT: Values: 162.5 19:42:38 INFO - 2016-01-06 19:42:38,317 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,317 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.21 Median:170.21 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,317 DEBUG : BROWSER_OUTPUT: Values: 170.2 19:42:38 INFO - 2016-01-06 19:42:38,317 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.17 Median:279.17 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: Values: 279.2 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5035.18 Median:5035.18 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: Values: 5035.2 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,319 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:945.18 Median:945.18 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: Values: 945.2 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:556.79 Median:556.79 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: Values: 556.8 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10458.33 Median:10458.33 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,320 DEBUG : BROWSER_OUTPUT: Values: 10458.3 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23627.91 Median:23627.91 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: Values: 23627.9 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:541.98 Median:541.98 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: Values: 542.0 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: 19:42:38 INFO - 2016-01-06 19:42:38,321 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 19:42:38 INFO - 2016-01-06 19:42:38,322 DEBUG : BROWSER_OUTPUT: Values: 4817.3 19:42:38 INFO - 2016-01-06 19:42:38,322 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:42:38 INFO - 2016-01-06 19:42:38,322 DEBUG : BROWSER_OUTPUT: 19:42:39 INFO - 2016-01-06 19:42:39,027 INFO : Browser exited with error code: 0 19:42:39 INFO - 2016-01-06 19:42:39,039 INFO : Completed test v8_7 (00:00:38) 19:42:39 INFO - 2016-01-06 19:42:39,356 INFO : Completed test suite (00:20:04) 19:42:39 INFO - 2016-01-06 19:42:39,356 DEBUG : Working with test: dromaeo_css 19:42:39 INFO - 2016-01-06 19:42:39,356 DEBUG : Generating results file: dromaeo_css 19:42:39 INFO - 2016-01-06 19:42:39,357 DEBUG : Working with test: kraken 19:42:39 INFO - 2016-01-06 19:42:39,357 DEBUG : Generating results file: kraken 19:42:39 INFO - 2016-01-06 19:42:39,358 INFO : javascript benchmark 19:42:39 INFO - 2016-01-06 19:42:39,358 DEBUG : Working with test: v8_7 19:42:39 INFO - 2016-01-06 19:42:39,358 DEBUG : Generating results file: v8_7 19:42:39 INFO - 2016-01-06 19:42:39,358 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:42:39 INFO - 2016-01-06 19:42:39,406 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 19:42:39 INFO - 2016-01-06 19:42:39,406 DEBUG : process_Request line: dromaeo_css 7414.26 graph.html#tests=[[72,53,35]] 19:42:39 INFO - 2016-01-06 19:42:39,406 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:42:39 INFO - 2016-01-06 19:42:39,435 DEBUG : process_Request line: kraken 1455.00 graph.html#tests=[[232,53,35]] 19:42:39 INFO - 2016-01-06 19:42:39,435 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:42:39 INFO - 2016-01-06 19:42:39,461 DEBUG : process_Request line: v8_7 23296.77 graph.html#tests=[[230,53,35]] 19:42:39 INFO - 2016-01-06 19:42:39,462 INFO : javascript benchmark 19:42:39 INFO - 2016-01-06 19:42:39,466 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3233.337165880994, 3361.613414457338, 3383.255683749574, 3383.289509203067, 3401.2857299967, 5534.501894829465, 5545.916811247829, 5558.888822223558, 5565.805196818107, 5585.8603534911645, 5550.555955523559, 5581.107022876341, 5585.832425027245, 5587.552995760339, 5587.804426845061, 4908.190148625473, 5068.189089745632, 5086.237064440326, 5174.094533456649, 5184.274201611777, 5116.539511443969, 5156.767945442445, 5160.071187186305, 5162.406323272834, 5172.327597412331, 4994.375703037121, 5074.822381216658, 5083.288339632454, 5133.3326667533165, 5140.460251673558, 5098.84703458895, 5111.2333150027425, 5114.360704911886, 5121.718305493197, 5137.7944882204665, 5086.084504789136, 5094.59243260542, 5152.458991805844, 5157.535821776059, 5169.379674439091, 5220.556252718524, 5266.526012658841, 5312.335958005249, 5334.0665383557725, 5345.973270133663, 5937.020391635375, 5947.762089516415, 5967.940320596782, 5969.224000879901, 5978.790742323998, 5706.914396284059, 5957.642541447483, 5970.34326224115, 5971.790987315467, 5989.131575921467, 5493.23094766733, 5523.4200408956885, 5571.052921003439, 5572.192032155355, 5605.495505404533, 5992.790252341147, 5995.220621319288, 5998.670073145931, 6047.758089676364, 6064.878702426015, 5648.5763567731765, 5762.711864406847, 5904.8780731660845, 5915.940840591625, 5918.171455996164, 5922.2597175353085, 6034.879302413927, 6041.78853740117, 6044.365341639064, 6073.089036644538, 5927.229460170237, 5945.048792193228, 5947.078202878561, 5947.226860508106, 5951.018082016505, 5677.37548869624, 5778.075507918713, 5931.703414829241, 5954.612950158225, 5958.493528050164, 4593.242115051046, 4594.402727645452, 4604.700694454917, 4609.147307748078, 4610.95389046112, 4094.5086589609436, 4106.958930410718, 4107.424960505532, 4118.732282401635, 4135.421041054255, 5469.0, 5588.972055139699, 5616.943830561724, 5676.148577713294, 5755.395683453261, 6044.486218671374, 6046.848828779228, 6067.332593414721, 6078.665673388006, 6094.11635312886, 5715.971420142872, 5730.914036289459, 5752.108423194411, 5754.108113242371, 5757.309122905194, 5030.89938201234, 5117.360329958755, 5138.768755406016, 5211.0, 5231.55531779803, 6123.050927106306, 6139.754409823647, 6146.0, 6147.0, 6148.354422785633, 5762.740676669476, 5996.070609055516, 6049.758009679739, 6053.697315134137, 6057.818265452043, 6027.095935609694, 6029.457348838626, 6121.908171377522, 6123.478999575351, 6124.136831511461, 5908.7045647718305, 5915.171875937286, 5923.170756094237, 5923.614965027259, 5927.9703601481715, 5809.970950145223, 5955.434233747787, 5992.610480318765, 5996.310424301261, 5998.310194327533, 5753.165790960204, 5808.07070868659, 5816.447437493432, 5822.388649191774, 5851.882962340815, 5808.128780682931, 5844.883102338014, 5849.766009359578, 5850.414958504116, 5854.092615644582, 5553.416891226363, 5612.326520817528, 5660.094384898396, 5745.683987380734, 5763.884722305446, 5831.562632802472, 5881.852953676193, 5890.852728681646, 5895.02732049207, 5904.439078287556, 5749.913751293818, 5792.044312688359, 5798.739056742373, 5800.56495762811, 5802.071668533014], "unit": "ms", "name": "dojo.html", "value": 5501.803414186113}, {"lowerIsBetter": false, "replicates": [575.7351618255602, 607.6536374266669, 614.339584946183, 614.5728718540613, 622.1383384013143, 34554.0, 35306.470402943945, 43492.130157396874, 44465.110697786025, 44501.554984450144, 47602.761986190024, 48358.51641483584, 48359.0, 48370.75814620922, 48395.27407088896, 8277.0, 8378.204070613281, 9109.0, 9268.582913768896, 9273.072692730706, 10911.399873006982, 11008.339499630009, 11153.330800151976, 11162.497687604076, 11183.32900025997, 10859.45702714865, 10894.945525272362, 10957.726056848604, 10973.890261097407, 10980.560777568888, 10891.618793342235, 10974.670759877137, 11000.669979900536, 11010.944945275303, 11020.063294619968, 10667.946660266649, 10833.945830270799, 10836.945815270952, 10850.24048316618, 10857.728556786065, 43619.78190109061, 43674.12651746947, 43712.3443148353, 43907.34138987918, 44016.11967760659, 3603.351396748584, 3718.2377612589626, 3768.0768211788286, 4318.287482565373, 4458.799354029076, 33931.32137357239, 33963.490547641806, 34247.0, 34311.0, 34342.31315373679, 3861.2663593917064, 3958.3072962231495, 3960.4257382679352, 4012.3179059559943, 4020.2160578687167, 315657.0, 316640.0, 316990.0, 317344.0, 433520.83239583916, 56741.71629141828, 75449.0, 76345.0, 76499.61750191325, 76520.23479765242, 70494.29505704877, 71130.28869711237, 71225.2877471229, 71273.64363178151, 71332.2866771336, 16250.268737906823, 16294.837051629569, 16329.836701633069, 16376.344946202256, 16390.754138687927, 13311.667208319675, 13532.59402217935, 13563.66090847717, 13570.864291357157, 13587.66030849217, 22661.0, 22699.205527806447, 22894.42763930882, 22900.4274893129, 22900.770992290196, 8070.273675369245, 8227.053888802744, 8308.0, 8315.459495132796, 8412.915870841336, 1063.6436793674206, 1079.5897558927713, 1136.7215032317135, 1173.700706319883, 1189.393409361232, 30880.53679194814, 30967.845160774054, 31443.52834707481, 31479.52780708291, 31886.84056579702, 1137.3460260350332, 1139.6182278936483, 1220.993895030519, 1229.1211783574786, 1246.4391024039219, 14087.506937257147, 15469.0, 15570.355140372743, 15822.0, 15836.52490425289, 388395.0, 390801.0, 394920.0, 396386.0, 396631.0, 59303.0, 59327.406725933055, 59330.0, 59445.0, 59625.40374596285, 1348.7437386896459, 1359.0282947692249, 1492.8283247426684, 1513.9167345795868, 1519.1416849480229, 29591.704082959757, 29706.3661498611, 29886.551701724926, 30033.849830750707, 30281.0, 50047.24929126139, 50335.74832125963, 50397.49602503928, 50479.49520504895, 50581.241281381546, 235688.0, 236183.0, 236263.0, 236370.0, 237286.0, 33691.0, 34162.48756268608, 34301.31397371989, 34698.0, 34851.47722784211, 45625.54374456213, 46714.766426167655, 49569.50430495649, 49759.751201243766, 50384.992300154525, 41918.0, 42136.0, 42251.15497690091, 42599.36100958426, 43496.56503435052, 41792.791036044626, 41819.7909010453, 42444.363334550624, 42509.36235956401, 42595.3610695846, 40153.39769903512, 41004.0, 41378.1724365517, 41525.792371037955, 41582.37626435545, 36711.26577468489, 36998.26003479969, 37401.62598373982, 37821.0, 37941.430878536295, 27382.178534643994, 27773.30566735767, 27885.34472434052, 35108.0, 36467.088322792144], "unit": "ms", "name": "ext.html", "value": 20810.71984641019}, {"lowerIsBetter": false, "replicates": [374.43647310797263, 379.6052105809957, 387.96702280306175, 389.978551179685, 392.68585131894486, 12068.517259309618, 15450.0, 18237.81762182378, 19522.70715939262, 19575.314863979762, 18039.63920721585, 18435.907820460914, 18561.814381856177, 19335.806641933577, 19348.806511934876, 5681.573881958839, 5815.534757219412, 5816.040353341694, 5819.505342045931, 5825.8834823303505, 4415.20526305265, 4528.275475923853, 5736.4550367715, 6403.9359606403805, 6633.137692100045, 6324.430801227889, 6520.445762110203, 6560.376764207393, 6589.17635295588, 6589.868202635968, 6320.715567799437, 6345.206849143857, 6346.9682651586445, 6347.206599175103, 6353.714082866259, 5670.829875103754, 5679.148127780824, 5680.6591604503865, 5686.601937864351, 5872.7063646817915, 23148.0, 23532.764672353398, 23566.7643323568, 24155.396115097086, 24452.26643200707, 2339.543788961136, 2474.6288056791445, 2485.179890636086, 2590.197038917923, 2643.603459481074, 15396.153211573359, 15512.689746205013, 15542.378304867792, 15589.298481568356, 16011.599710007227, 2104.7895210478982, 2125.978740212609, 2155.4180371299663, 2187.704659870913, 2195.879226642534, 73018.0, 74819.62590187069, 76138.23861761422, 76779.23220767776, 77343.6132819338, 17217.827821721872, 17482.30070797154, 17531.211095500734, 17594.824051759573, 17654.558636033944, 26608.60087098695, 26661.600075998875, 26711.732882670924, 26981.0, 26991.730082698923, 10224.335418197794, 10460.0, 10467.267291289536, 10467.947660261802, 10545.103666188306, 8942.910570894208, 8995.730128096167, 8996.100389960951, 9057.954710226406, 9115.225205857576, 8590.699325523577, 8714.825703486022, 8763.0, 8771.517566533774, 8771.868421973675, 1203.8495188101488, 1208.8851559101872, 1210.8910198082215, 1223.8409006829233, 2147.871127732341, 503.97228152451964, 506.67572753437827, 509.48541972607933, 533.8692020454936, 535.4698848140391, 10736.516856741466, 11177.552897883994, 11570.074394048455, 12869.0, 13093.279869607262, 542.4087744358668, 548.4816848078607, 551.2778260478785, 558.1989844573051, 565.2877374508149, 6654.83362915931, 6715.2613212546585, 6833.93166068333, 6849.2808255132495, 6851.109355783816, 606908.0, 607336.0, 608518.0, 612077.0, 623508.0, 78762.0, 79099.0, 80197.18816871007, 82430.58784706038, 83877.16122838815, 598.2043881637499, 600.129811772931, 602.4999250621973, 603.0833133636847, 603.2429301226932, 11946.880531194924, 12282.447289872156, 12302.938485307515, 12371.69070773238, 12373.381330933596, 39060.80469597634, 39228.607713922494, 39604.60395396009, 41067.0, 41601.58398415977, 51410.0, 53327.73336133295, 53600.46399535955, 54448.0, 54458.45541544692, 7434.73978410753, 7562.054743157106, 7690.730824421118, 7746.147923728498, 7761.379089672925, 10868.891311087104, 11044.226904116635, 11904.345261010727, 13899.791503127259, 14001.439942402598, 10162.441065741456, 10902.182336324966, 11076.723081923017, 11081.224314297922, 11157.10743140563, 10845.186611004365, 10911.399873006743, 10939.0, 10972.890271097187, 11172.664820055412, 11091.389973551532, 11151.275167114112, 11289.943550282196, 11416.372099534605, 11447.427628618703, 7257.310555497219, 7327.890081648886, 7518.28576285252, 7553.131389890013, 7559.3196612305155, 10764.515596798006, 11282.548698051985, 11303.886961130283, 11497.770044599229, 11504.597339093092], "unit": "ms", "name": "jquery.html", "value": 8893.592252779805}, {"lowerIsBetter": false, "replicates": [1568.4196847166547, 1590.4433448293107, 1629.6414788746476, 1842.9262829486813, 1948.3570421760812, 7228.457865660071, 7343.082114735658, 7729.768106956793, 7916.6833326666865, 7916.920830791698, 7698.114716807569, 7795.649195786189, 7884.32983196429, 7887.0, 7891.1714270001685, 2360.173939121307, 2432.1244352033254, 2432.3311089450453, 2439.9390015249674, 2442.5237078769646, 2151.34384012876, 2189.277538412328, 2388.8447250928634, 2449.081594402099, 2452.411421258894, 2385.439421735891, 2396.364963284731, 2397.7841994220516, 2398.796102331304, 2399.448126930807, 2450.8284420090513, 2472.925812225636, 2475.0718480569785, 2475.8266921315517, 2486.1422809130822, 2393.114547617375, 2407.3861165402886, 2411.963820542693, 2421.4672771990136, 2428.1744206969715, 7952.045754509438, 8061.234182752629, 8105.473144245605, 8106.027276726831, 8111.472754270953, 5028.547430731252, 5120.180771076647, 5125.0, 5126.333576635014, 5130.050940575968, 3246.3020450603, 3264.6408895021527, 3331.7001469867587, 3400.0989737719524, 3412.7269818414466, 1155.8901904319075, 1156.8900954409319, 1183.7040739815047, 1187.5249900039967, 1195.0, 11271.041961433288, 11304.0, 11316.943415282953, 11317.264377815416, 11323.037541808957, 4545.36364908913, 4549.249373853343, 4591.150637132141, 4603.125406172817, 4629.560191781776, 9416.858747118798, 9421.764455888657, 9444.8583271251, 9445.386049906734, 9462.337636365526, 5120.539151476385, 5133.8716532087, 5143.048536020848, 5151.407588127413, 5170.1469257572835, 4651.465081515669, 4661.95338046622, 4666.2767271073035, 4667.579917807346, 4669.533046695371, 3707.277080969185, 4067.2678917795083, 4084.0402505411203, 4127.442795222667, 4161.0221597924465, 1262.0786825617167, 1266.9809952850715, 1304.1197191895433, 1305.9738805223842, 1317.8155058291848, 1297.928613926243, 1408.1058527834898, 1438.0940007795023, 1466.992665036668, 1470.2207829850197, 7718.845623087507, 7764.3788496920115, 7809.414293927978, 7858.921410785933, 7861.2531809478005, 1224.6632176151452, 1233.2230694662305, 1233.6422437493027, 1238.2143017238488, 1249.1006475337742, 6188.969055154786, 6196.225471816023, 6287.0, 6298.338674439209, 6304.779332723424, 9379.0, 9587.041295870496, 9593.952030239805, 9604.99147589507, 9611.711648650551, 9364.484953327496, 9517.524123793783, 9539.90460095404, 9543.47510886894, 9543.61825526985, 7561.810954726176, 7656.081270247606, 7660.923390766133, 7662.540247585162, 7665.770026899202, 7295.306945840137, 7375.889361659472, 7402.4078073755045, 7410.666520006721, 7414.036175297177, 8634.481931084156, 8642.870356944524, 8871.911280887367, 8892.555372231493, 8909.643614255358, 8672.653093876173, 8728.214460698568, 8736.69421570242, 8758.737237882873, 9096.499692516974, 7387.335139837441, 7443.665035073543, 7462.141853686896, 7467.1039475263315, 7468.402527797872, 6758.526903116931, 7129.7861064168155, 7719.884201737091, 7800.4149688774705, 7853.057633084067, 7024.121984751906, 7204.351608355273, 7240.05879235696, 7254.16577093641, 7443.516171448839, 7311.0, 7408.222136675571, 7419.22098179705, 7488.962555187407, 7513.62431878393, 7286.089238845145, 7332.5967071811565, 7361.705531778669, 7408.333250007525, 7408.888866666896, 6511.38141876521, 6581.0, 6587.538872278895, 6594.934050659432, 6628.105205797156, 6948.305169483011, 6979.30206979298, 6983.1620205575655, 6991.126109236346, 6994.440444764508], "unit": "ms", "name": "mootools.html", "value": 4775.080196717581}, {"lowerIsBetter": false, "replicates": [345.5469749702501, 349.98600055997747, 355.2965129044492, 357.39778473272537, 357.6548630571499, 5921.703914804253, 5992.700364981744, 6021.0667346561295, 6021.3376528581875, 6034.24571928509, 5833.358330583633, 5938.346781854003, 5946.643201407907, 5953.493953013988, 5978.432048955342, 4634.026854360588, 4666.650001249903, 4671.743054132022, 4671.976640116794, 4672.742999135046, 4653.0228651983125, 4662.557057079589, 4668.439787225538, 4677.789499472532, 4682.859514214579, 4594.402727645402, 4654.348391225231, 4686.671932964694, 4700.27145792401, 4706.29405589161, 4647.372604698357, 4662.04428092242, 4665.09030739006, 4667.6965997210245, 4690.671652984292, 3997.6601988830985, 4135.255653982282, 4141.730787498803, 4151.024509240327, 4166.604172603597, 5599.020171470017, 5642.7178641067785, 5740.56945729072, 5746.482816546489, 5752.482276595127, 5337.70642614656, 5365.08793505105, 5381.0852155133725, 5382.0312343778105, 5386.8922621547745, 6332.810015699536, 6339.175907132089, 6339.524535659844, 6343.714532846033, 6343.936560634427, 4457.375967364572, 4529.343245229424, 4547.18150732868, 4558.58972692456, 4587.954120458786, 6595.373439523238, 6612.636305003271, 6617.7022034008105, 6619.073329733841, 6624.138861947916, 4697.342372067913, 4705.12955103307, 4710.010897711519, 4710.269908164171, 4710.36410084641, 4628.051249493846, 4693.131770622377, 4767.046590681808, 4768.213244814636, 4771.809127634856, 5358.356997160366, 5367.033933891937, 5370.436104209002, 5371.221172929903, 5371.811986580451, 6282.685865706696, 6299.96850015747, 6332.398422149889, 6337.841553961188, 6338.7464501420345, 1714.202895653502, 1751.1331890714177, 1751.6321572469924, 1753.3512600337956, 1753.4739578126766, 1163.0521125282867, 1169.0881112732084, 1173.565780661161, 1180.0677464802882, 1193.964181074569, 6203.069539569101, 6210.223722034746, 6214.161088253119, 6214.40963108504, 6217.813465596039, 6537.705803238866, 6561.475081993429, 6605.735770569219, 6606.339366063356, 6607.273199948002, 6078.635281883101, 6166.321704612489, 6213.565050446516, 6216.533759968021, 6220.937790622036, 3620.456931460302, 3649.94525082124, 3654.086478380405, 3655.597884232732, 3656.7074634029213, 6493.090967264587, 6591.769288074895, 6652.90020649681, 6726.39462448382, 6766.72933082681, 6724.798256052326, 6748.021536877223, 6765.526413151032, 6769.018492318586, 6774.525783195228, 6675.566088204251, 6721.059051732761, 6733.595984240961, 6735.6295403753265, 6740.022696708949, 6439.935600643934, 6642.800715978335, 6651.0355998379, 6655.966720166368, 6660.533762636763, 6596.967015164893, 6608.4052435281055, 6613.570117942319, 6613.867722645617, 6618.569792963442, 6815.318468153145, 6838.897416538656, 6846.5892046477375, 6846.897296540456, 6853.862922741617, 4660.673752837269, 4829.710217386968, 4833.419989601271, 4835.2505361669, 4838.491958344464, 6536.509761767981, 6582.144321238208, 6591.505637077143, 6593.340665933369, 6596.67016649156, 6417.935820641734, 6479.092926990131, 6479.384458476437, 6480.351964803671, 6481.773137940149, 6302.747890084345, 6438.323975982455, 6462.289148193789, 6466.838329041623, 6471.191101112361, 6357.586756860975, 6360.0, 6362.681865906594, 6364.459020983269, 6368.23581170244, 6364.840878978062, 6434.453071488976, 6497.02544618311, 6497.74009039652, 6500.3174666659315, 6333.081703153109, 6344.873102537831, 6346.587471814318, 6346.777862774781, 6349.301576826453], "unit": "ms", "name": "prototype.html", "value": 4885.234916938886}, {"lowerIsBetter": false, "replicates": [853.0914575976584, 907.1246247371286, 949.9335046546743, 955.3169483819067, 975.5366201054495, 15400.460983865567, 15613.765793513077, 15680.372785088583, 15710.92144539277, 15743.448979285728, 14647.853521464782, 14702.264886755645, 14785.038972466782, 14798.704025919475, 14808.407663693439, 773.1109224391952, 786.2334224131483, 936.5223735894675, 957.0525180071713, 959.6065613098631, 906.9410488318272, 945.1021529546923, 949.103097572794, 953.8378475659155, 954.9618015279415, 816.7019038051128, 954.4988880837581, 983.2920297385871, 985.3201291109148, 985.571276494727, 802.7471346525855, 970.1898914406444, 988.0, 988.0415996483399, 989.3272574649236, 715.9463040271949, 733.8238822682571, 743.6542007966322, 743.8661041012616, 744.7616762635959, 14393.71212575753, 14549.636259093502, 14592.270386480739, 14612.050216735877, 14616.196109213988, 13468.326583670778, 13832.30838458083, 13975.161490310615, 14112.576622701336, 14160.221187834664, 11242.550297988071, 11365.31808091517, 11520.0, 11591.362475063868, 11658.59194928182, 3715.498407714951, 3847.615238476158, 3947.7433966792355, 3948.3879998600264, 3977.2244412339605, 50957.235641465406, 51307.74346128283, 56027.43972560263, 56100.71949640226, 56144.71927640377, 11600.88399115998, 11770.0, 11872.228305160137, 11901.821472677917, 12050.638480845588, 21344.466388340417, 21380.572388552453, 21381.46546336354, 21387.8930605346, 21457.67813482799, 8719.34604904622, 8832.0, 8835.823283534293, 8911.0, 9206.26349892007, 4589.977050114795, 4812.133815913099, 4816.20532612122, 4817.325574419584, 4914.533119353656, 3280.557124788123, 3305.388503126929, 3307.851146698404, 3309.619393769699, 3310.2055506678225, 498.9201727723547, 519.4831143012651, 520.0976306108898, 523.1551045062273, 524.4283730733518, 638.565775272819, 654.7511945460694, 680.6052489556145, 706.7243774927782, 753.5290443472829, 8834.64661413549, 9396.107369799858, 9560.521973901416, 9575.760605984906, 9871.703848884545, 677.2144312597363, 705.4497491956282, 717.3902183144286, 743.353282644103, 763.4846478626905, 4421.668374871833, 4519.593236608721, 4649.67452278344, 4665.066986602693, 4680.789364478538, 67473.32526674769, 68375.0, 69102.65448672725, 69358.30641693619, 69399.0, 59756.402435975084, 60801.695991520646, 61229.69385153046, 61450.38549614447, 61495.69252153711, 50253.748731256106, 50877.745611271705, 51667.74166129145, 52238.73880630572, 53173.0, 9036.051214622634, 9258.444493330422, 9605.567749451431, 9674.467904265051, 9737.80524389494, 40189.59810401859, 40768.3884741735, 40939.59060409477, 41879.162416750885, 42166.789166053975, 46892.29661555011, 49463.25805112854, 49720.502794973036, 49864.0, 50270.24594631157, 9076.954615226881, 9211.124943130391, 9422.199113075465, 9472.810543788948, 9740.61037558518, 13303.534376296782, 13702.10936289138, 13856.30718464093, 13951.511697090553, 13958.23229722375, 11132.833007505056, 11572.768544629229, 11882.28706277626, 12016.098792590416, 12108.75782484363, 12285.262884226975, 12991.610251692464, 13181.406836692564, 13409.66475838112, 13459.125156865164, 10976.23166378346, 11014.834777478183, 11091.112710983263, 11093.44532773342, 11529.308241505536, 11122.388268645302, 11467.655970320904, 11574.942125289319, 11579.710507237387, 12084.939575302067, 10306.381617102998, 10393.37639741618, 10404.895951040393, 10678.359298442118, 10782.406967616856], "unit": "ms", "name": "yui.html", "value": 6992.508209608821}], "name": "dromaeo_css", "value": 7414.262151674804}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 103.0, 110.0, 109.0, 110.0, 98.0, 109.0, 105.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 106.9}, {"lowerIsBetter": true, "replicates": [149.0, 120.0, 155.0, 160.0, 128.0, 147.0, 145.0, 123.0, 159.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.2}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 161.0, 160.0, 159.0, 161.0, 160.0, 160.0, 162.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 69.0, 148.0, 72.0, 72.0, 71.0, 71.0, 156.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.1}, {"lowerIsBetter": true, "replicates": [88.0, 88.0, 134.0, 88.0, 89.0, 88.0, 142.0, 88.0, 88.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 102.4}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [52.0, 54.0, 55.0, 55.0, 58.0, 55.0, 55.0, 55.0, 52.0, 52.0], "unit": "ms", "name": "json-parse-financial", "value": 54.3}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0, 63.0, 62.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.6}, {"lowerIsBetter": true, "replicates": [86.0, 76.0, 78.0, 77.0, 77.0, 78.0, 77.0, 85.0, 80.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.2}, {"lowerIsBetter": true, "replicates": [108.0, 115.0, 113.0, 105.0, 120.0, 107.0, 117.0, 117.0, 115.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.7}, {"lowerIsBetter": true, "replicates": [135.0, 137.0, 135.0, 134.0, 136.0, 135.0, 135.0, 134.0, 136.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 135.1}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 65.0, 65.0, 62.0, 65.0, 62.0, 63.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.7}], "name": "kraken", "value": 1454.9999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.46953696181967], "unit": "score", "name": "Richards", "value": 217.28381000000007}, {"lowerIsBetter": false, "replicates": [170.2127659574468], "unit": "score", "name": "DeltaBlue", "value": 388.4432500000001}, {"lowerIsBetter": false, "replicates": [279.17364600781684], "unit": "score", "name": "Encrypt", "value": 953.4603419999996}, {"lowerIsBetter": false, "replicates": [5035.175879396985], "unit": "score", "name": "Decrypt", "value": 52.864290419161705}, {"lowerIsBetter": false, "replicates": [945.179584120983], "unit": "score", "name": "RayTrace", "value": 782.908362}, {"lowerIsBetter": false, "replicates": [556.7928730512249], "unit": "score", "name": "Earley", "value": 1196.967548}, {"lowerIsBetter": false, "replicates": [10458.333333333334], "unit": "score", "name": "Boyer", "value": 63.725545816733074}, {"lowerIsBetter": false, "replicates": [23627.906976744187], "unit": "score", "name": "RegExp", "value": 38.55546751968505}, {"lowerIsBetter": false, "replicates": [541.9806243272336], "unit": "score", "name": "Splay", "value": 150.3577735849056}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23296.769066756395}]} 19:42:39 INFO - RETURN: dromaeo_css: 7414.26 19:42:39 INFO - RETURN: kraken: 1455.00 19:42:39 INFO - RETURN: v8_7: 23296.77 19:42:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23296.77"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1455.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7414.26"}}} 19:42:39 INFO - Return code: 0 19:42:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:42:39 INFO - # TBPL SUCCESS # 19:42:39 INFO - Running post-action listener: _resource_record_post_action 19:42:39 INFO - Running post-run listener: _resource_record_post_run 19:42:40 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 315392; Write bytes: 496398336; Read time: 60; Write time: 337252 19:42:40 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 234389504; Read time: 0; Write time: 214100 19:42:40 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 315392; Write bytes: 262008832; Read time: 60; Write time: 123152 19:42:40 INFO - Running post-run listener: _upload_blobber_files 19:42:40 INFO - Blob upload gear active. 19:42:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:42:40 INFO - Copying logs to upload dir... 19:42:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1263.725272 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-01-06 19:42:40.453066) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-06 19:42:40.456459) ========= 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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009952 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452128716/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-06 19:42:40.511748) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:42:40.512506) ========= 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=32c5991c409366f7fec8a718000001d2-1452135958.102464-1241123837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:42:40.566080) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-06 19:42:40.566802) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-06 19:42:40.567553) ========= ========= Total master_lag: 0.24 =========