builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1452549749.64 results: success (0) buildid: 20160111120139 builduid: ddc8809bf7614cfaaa67468308ab5e83 revision: 0488d79e6d74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.638537) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.638995) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.639286) ========= 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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005403 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.662469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.662827) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.677033) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.677398) ========= 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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004607 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.733274) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.733569) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.733929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.734204) ========= 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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-11 14:02:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.6M=0s 2016-01-11 14:02:30 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151699 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.898729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.899054) ========= 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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022560 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.948761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:29.949120) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0488d79e6d74 --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 0488d79e6d74 --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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-11 14:02:30,830 Setting DEBUG logging. 2016-01-11 14:02:30,830 attempt 1/10 2016-01-11 14:02:30,830 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0488d79e6d74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-11 14:02:31,124 unpacking tar archive at: mozilla-beta-0488d79e6d74/testing/mozharness/ program finished with exit code 0 elapsedTime=0.573761 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:30.536141) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:30.536484) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:30.551156) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:30.551612) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-11 14:02:30.552432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-11 14:02:30.552734) ========= /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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:02:31 INFO - MultiFileLogger online at 20160111 14:02:31 in /builds/slave/test-pgo 14:02:31 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 14:02:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:02:31 INFO - {'append_to_log': False, 14:02:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:02:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:02:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:02:31 INFO - 'branch': 'Mozilla-Beta', 14:02:31 INFO - 'buildbot_json_path': 'buildprops.json', 14:02:31 INFO - 'config_files': ('talos/linux_config.py',), 14:02:31 INFO - 'default_actions': ('clobber', 14:02:31 INFO - 'read-buildbot-config', 14:02:31 INFO - 'download-and-extract', 14:02:31 INFO - 'populate-webroot', 14:02:31 INFO - 'create-virtualenv', 14:02:31 INFO - 'install', 14:02:31 INFO - 'run-tests'), 14:02:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:02:31 INFO - 'download_minidump_stackwalk': True, 14:02:31 INFO - 'download_symbols': 'ondemand', 14:02:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:02:31 INFO - 'tooltool.py': '/tools/tooltool.py', 14:02:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:02:31 INFO - '/tools/misc-python/virtualenv.py')}, 14:02:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:02:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:02:31 INFO - 'installer_path': 'installer.exe', 14:02:31 INFO - 'log_level': 'info', 14:02:31 INFO - 'log_name': 'talos', 14:02:31 INFO - 'log_to_console': True, 14:02:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:02:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:02:31 INFO - 'opt_config_files': (), 14:02:31 INFO - 'pip_index': False, 14:02:31 INFO - 'sps_profile': False, 14:02:31 INFO - 'sps_profile_interval': 0, 14:02:31 INFO - 'suite': 'dromaeojs', 14:02:31 INFO - 'system_bits': '32', 14:02:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:02:31 INFO - 'title': 'talos-linux64-ix-056', 14:02:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:02:31 INFO - 'use_talos_json': True, 14:02:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:02:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:02:31 INFO - 'work_dir': 'build'} 14:02:31 INFO - ##### 14:02:31 INFO - ##### Running clobber step. 14:02:31 INFO - ##### 14:02:31 INFO - Running pre-action listener: _resource_record_pre_action 14:02:31 INFO - Running main action method: clobber 14:02:31 INFO - rmtree: /builds/slave/test-pgo/build 14:02:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:02:31 INFO - Running post-action listener: _resource_record_post_action 14:02:31 INFO - ##### 14:02:31 INFO - ##### Running read-buildbot-config step. 14:02:31 INFO - ##### 14:02:31 INFO - Running pre-action listener: _resource_record_pre_action 14:02:31 INFO - Running main action method: read_buildbot_config 14:02:31 INFO - Using buildbot properties: 14:02:31 INFO - { 14:02:31 INFO - "properties": { 14:02:31 INFO - "buildnumber": 71, 14:02:31 INFO - "product": "firefox", 14:02:31 INFO - "script_repo_revision": "production", 14:02:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:02:31 INFO - "repository": "", 14:02:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:02:31 INFO - "buildid": "20160111120139", 14:02:31 INFO - "slavename": "talos-linux64-ix-056", 14:02:31 INFO - "pgo_build": "True", 14:02:31 INFO - "basedir": "/builds/slave/test-pgo", 14:02:31 INFO - "project": "", 14:02:31 INFO - "platform": "ubuntu64_hw", 14:02:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:02:31 INFO - "slavebuilddir": "test", 14:02:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:02:31 INFO - "branch": "mozilla-beta", 14:02:31 INFO - "repo_path": "releases/mozilla-beta", 14:02:31 INFO - "stage_platform": "linux64-pgo", 14:02:31 INFO - "builduid": "ddc8809bf7614cfaaa67468308ab5e83", 14:02:31 INFO - "revision": "0488d79e6d74" 14:02:31 INFO - }, 14:02:31 INFO - "sourcestamp": { 14:02:31 INFO - "repository": "", 14:02:31 INFO - "hasPatch": false, 14:02:31 INFO - "project": "", 14:02:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:02:31 INFO - "changes": [ 14:02:31 INFO - { 14:02:31 INFO - "category": null, 14:02:31 INFO - "files": [ 14:02:31 INFO - { 14:02:31 INFO - "url": null, 14:02:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:02:31 INFO - } 14:02:31 INFO - ], 14:02:31 INFO - "repository": "", 14:02:31 INFO - "rev": "0488d79e6d74", 14:02:31 INFO - "who": "sendchange", 14:02:31 INFO - "when": 1452549705, 14:02:31 INFO - "number": 7444766, 14:02:31 INFO - "comments": "Bug 1233182: Part 2. reversed some lines in the merge. r=botond a=ritu", 14:02:31 INFO - "project": "", 14:02:31 INFO - "at": "Mon 11 Jan 2016 14:01:45", 14:02:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:02:31 INFO - "revlink": "", 14:02:31 INFO - "properties": [ 14:02:31 INFO - [ 14:02:31 INFO - "buildid", 14:02:31 INFO - "20160111120139", 14:02:31 INFO - "Change" 14:02:31 INFO - ], 14:02:31 INFO - [ 14:02:31 INFO - "builduid", 14:02:31 INFO - "ddc8809bf7614cfaaa67468308ab5e83", 14:02:31 INFO - "Change" 14:02:31 INFO - ], 14:02:31 INFO - [ 14:02:31 INFO - "pgo_build", 14:02:31 INFO - "True", 14:02:31 INFO - "Change" 14:02:31 INFO - ] 14:02:31 INFO - ], 14:02:31 INFO - "revision": "0488d79e6d74" 14:02:31 INFO - } 14:02:31 INFO - ], 14:02:31 INFO - "revision": "0488d79e6d74" 14:02:31 INFO - } 14:02:31 INFO - } 14:02:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:02:31 INFO - Running post-action listener: _resource_record_post_action 14:02:31 INFO - ##### 14:02:31 INFO - ##### Running download-and-extract step. 14:02:31 INFO - ##### 14:02:31 INFO - Running pre-action listener: _resource_record_pre_action 14:02:31 INFO - Running main action method: download_and_extract 14:02:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:02:31 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')]} 14:02:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/test_packages.json 14:02:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:02:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:02:32 INFO - Downloaded 1270 bytes. 14:02:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:02:32 INFO - Using the following test package requirements: 14:02:32 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:02:32 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:02:32 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'jsshell-linux-x86_64.zip'], 14:02:32 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:02:32 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:02:32 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:02:32 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:02:32 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:02:32 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:02:32 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:02:32 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:02:32 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:02:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:38 INFO - Downloaded 22572072 bytes. 14:02:38 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 14:02:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:02:38 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 14:02:39 INFO - Return code: 0 14:02:39 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 14:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:02:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:41 INFO - Downloaded 22572072 bytes. 14:02:41 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 14:02:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:02:41 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 14:02:42 INFO - Return code: 0 14:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:02:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:47 INFO - Downloaded 11131970 bytes. 14:02:47 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 14:02:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:02:47 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 14:02:47 INFO - Return code: 0 14:02: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')]} 14:02:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:02:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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 14:02:50 INFO - Downloaded 56622826 bytes. 14:02:50 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:02:50 INFO - mkdir: /builds/slave/test-pgo/properties 14:02:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:02:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:02:50 INFO - Contents: 14:02:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:02:50 INFO - Running post-action listener: _resource_record_post_action 14:02:50 INFO - Running post-action listener: set_extra_try_arguments 14:02:50 INFO - ##### 14:02:50 INFO - ##### Running populate-webroot step. 14:02:50 INFO - ##### 14:02:50 INFO - Running pre-action listener: _resource_record_pre_action 14:02:50 INFO - Running main action method: populate_webroot 14:02:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:02:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:02:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:02:50 INFO - '../fennec_ids.txt'], 14:02:50 INFO - 'tests': ['tcheck2']}, 14:02:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:02:50 INFO - '--tppagecycles', 14:02:50 INFO - '7'], 14:02:50 INFO - 'tests': ['tsvgm']}}, 14:02:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:02:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:02:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:02:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:02:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:02:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:02:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'tests': ['damp', 'tps']}, 14:02:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['damp', 'tps']}, 14:02:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:02:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:02:50 INFO - 'other': {'tests': ['a11yr', 14:02:50 INFO - 'ts_paint', 14:02:50 INFO - 'tpaint', 14:02:50 INFO - 'sessionrestore', 14:02:50 INFO - 'sessionrestore_no_auto_restore']}, 14:02:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['a11yr', 14:02:50 INFO - 'ts_paint', 14:02:50 INFO - 'tpaint', 14:02:50 INFO - 'sessionrestore', 14:02:50 INFO - 'sessionrestore_no_auto_restore']}, 14:02:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['a11yr', 14:02:50 INFO - 'ts_paint', 14:02:50 INFO - 'tpaint', 14:02:50 INFO - 'sessionrestore', 14:02:50 INFO - 'sessionrestore_no_auto_restore']}, 14:02:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['a11yr', 14:02:50 INFO - 'ts_paint', 14:02:50 INFO - 'tpaint', 14:02:50 INFO - 'sessionrestore', 14:02:50 INFO - 'sessionrestore_no_auto_restore']}, 14:02:50 INFO - 'other_l64': {'tests': ['a11yr', 14:02:50 INFO - 'ts_paint', 14:02:50 INFO - 'tpaint', 14:02:50 INFO - 'sessionrestore', 14:02:50 INFO - 'sessionrestore_no_auto_restore']}, 14:02:50 INFO - 'other_nol64': {'tests': ['a11yr', 14:02:50 INFO - 'ts_paint', 14:02:50 INFO - 'tpaint', 14:02:50 INFO - 'sessionrestore', 14:02:50 INFO - 'sessionrestore_no_auto_restore']}, 14:02:50 INFO - 'svgr': {'tests': ['tsvgx', 14:02:50 INFO - 'tsvgr_opacity', 14:02:50 INFO - 'tart', 14:02:50 INFO - 'tscrollx', 14:02:50 INFO - 'cart']}, 14:02:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['tsvgx', 14:02:50 INFO - 'tsvgr_opacity', 14:02:50 INFO - 'tart', 14:02:50 INFO - 'tscrollx', 14:02:50 INFO - 'cart']}, 14:02:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'tests': ['tp5o']}, 14:02:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'talos_options': ['--e10s'], 14:02:50 INFO - 'tests': ['tp5o']}, 14:02:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'talos_options': ['--xperf_path', 14:02:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:02:50 INFO - 'tests': ['tp5n']}, 14:02:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:02:50 INFO - 'talos_options': ['--e10s', 14:02:50 INFO - '--xperf_path', 14:02:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:02:50 INFO - 'tests': ['tp5n']}}, 14:02:50 INFO - 'talos.zip': {'path': '', 14:02:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:02:50 INFO - Running post-action listener: _resource_record_post_action 14:02:50 INFO - ##### 14:02:50 INFO - ##### Running create-virtualenv step. 14:02:50 INFO - ##### 14:02:50 INFO - Running pre-action listener: _resource_record_pre_action 14:02:50 INFO - Running main action method: create_virtualenv 14:02:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:02:50 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 14:02:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:02:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:02:50 INFO - Using real prefix '/usr' 14:02:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:02:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:02:53 INFO - Installing pip.................done. 14:02:53 INFO - Return code: 0 14:02:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:02:53 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 14:02:53 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 14:02:53 INFO - Using env: {'DISPLAY': ':0', 14:02:53 INFO - 'HOME': '/home/cltbld', 14:02:53 INFO - 'LANG': 'en_US.UTF-8', 14:02:53 INFO - 'LANGUAGE': 'en_US:en', 14:02:53 INFO - 'LOGNAME': 'cltbld', 14:02:53 INFO - 'MAIL': '/var/mail/cltbld', 14:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:53 INFO - 'MOZ_NO_REMOTE': '1', 14:02:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:53 INFO - 'NO_EM_RESTART': '1', 14:02:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:02:53 INFO - 'PWD': '/builds/slave/test-pgo', 14:02:53 INFO - 'SHELL': '/bin/bash', 14:02:53 INFO - 'SHLVL': '1', 14:02:53 INFO - 'TERM': 'linux', 14:02:53 INFO - 'TMOUT': '86400', 14:02:53 INFO - 'USER': 'cltbld', 14:02:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:53 INFO - '_': '/tools/buildbot/bin/python'} 14:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:53 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 14:02:53 INFO - Cleaning up... 14:02:53 INFO - Return code: 0 14:02:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:02:53 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 14:02:53 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 14:02:53 INFO - Using env: {'DISPLAY': ':0', 14:02:53 INFO - 'HOME': '/home/cltbld', 14:02:53 INFO - 'LANG': 'en_US.UTF-8', 14:02:53 INFO - 'LANGUAGE': 'en_US:en', 14:02:53 INFO - 'LOGNAME': 'cltbld', 14:02:53 INFO - 'MAIL': '/var/mail/cltbld', 14:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:53 INFO - 'MOZ_NO_REMOTE': '1', 14:02:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:53 INFO - 'NO_EM_RESTART': '1', 14:02:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:02:53 INFO - 'PWD': '/builds/slave/test-pgo', 14:02:53 INFO - 'SHELL': '/bin/bash', 14:02:53 INFO - 'SHLVL': '1', 14:02:53 INFO - 'TERM': 'linux', 14:02:53 INFO - 'TMOUT': '86400', 14:02:53 INFO - 'USER': 'cltbld', 14:02:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:53 INFO - '_': '/tools/buildbot/bin/python'} 14:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:53 INFO - Downloading/unpacking psutil>=0.7.1 14:02:53 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 14:02:53 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 14:02:53 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 14:02:53 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 14:02:53 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 14:02:53 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 14:02:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:02:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:02:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:02:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:02:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:02:55 INFO - Installing collected packages: psutil 14:02:55 INFO - Running setup.py install for psutil 14:02:55 INFO - building 'psutil._psutil_linux' extension 14:02:55 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 14:02:55 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 14:02:55 INFO - building 'psutil._psutil_posix' extension 14:02:55 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 14:02:55 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 14:02:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:02:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:02:55 INFO - Successfully installed psutil 14:02:55 INFO - Cleaning up... 14:02:55 INFO - Return code: 0 14:02:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:02:55 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')]} 14:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:55 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')]} 14:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:55 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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:02:55 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 14:02:55 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 14:02:55 INFO - Using env: {'DISPLAY': ':0', 14:02:55 INFO - 'HOME': '/home/cltbld', 14:02:55 INFO - 'LANG': 'en_US.UTF-8', 14:02:55 INFO - 'LANGUAGE': 'en_US:en', 14:02:55 INFO - 'LOGNAME': 'cltbld', 14:02:55 INFO - 'MAIL': '/var/mail/cltbld', 14:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:55 INFO - 'MOZ_NO_REMOTE': '1', 14:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:55 INFO - 'NO_EM_RESTART': '1', 14:02:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:02:55 INFO - 'PWD': '/builds/slave/test-pgo', 14:02:55 INFO - 'SHELL': '/bin/bash', 14:02:55 INFO - 'SHLVL': '1', 14:02:55 INFO - 'TERM': 'linux', 14:02:55 INFO - 'TMOUT': '86400', 14:02:55 INFO - 'USER': 'cltbld', 14:02:55 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:55 INFO - '_': '/tools/buildbot/bin/python'} 14:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:02:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:02:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:02:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:02:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:02:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:02:57 INFO - Installing collected packages: mozsystemmonitor 14:02:57 INFO - Running setup.py install for mozsystemmonitor 14:02:57 INFO - Successfully installed mozsystemmonitor 14:02:57 INFO - Cleaning up... 14:02:57 INFO - Return code: 0 14:02:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:02:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 14:02:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:02:57 INFO - Using env: {'DISPLAY': ':0', 14:02:57 INFO - 'HOME': '/home/cltbld', 14:02:57 INFO - 'LANG': 'en_US.UTF-8', 14:02:57 INFO - 'LANGUAGE': 'en_US:en', 14:02:57 INFO - 'LOGNAME': 'cltbld', 14:02:57 INFO - 'MAIL': '/var/mail/cltbld', 14:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:57 INFO - 'MOZ_NO_REMOTE': '1', 14:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:57 INFO - 'NO_EM_RESTART': '1', 14:02:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:02:57 INFO - 'PWD': '/builds/slave/test-pgo', 14:02:57 INFO - 'SHELL': '/bin/bash', 14:02:57 INFO - 'SHLVL': '1', 14:02:57 INFO - 'TERM': 'linux', 14:02:57 INFO - 'TMOUT': '86400', 14:02:57 INFO - 'USER': 'cltbld', 14:02:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:57 INFO - '_': '/tools/buildbot/bin/python'} 14:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:58 INFO - Downloading/unpacking blobuploader==1.2.4 14:02: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 14:02: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 14:02: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 14:02: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 14:02: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 14:02: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 14:02:59 INFO - Downloading blobuploader-1.2.4.tar.gz 14:02:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:02:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:02:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:02:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:02:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:03:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:03:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:03:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:00 INFO - Downloading docopt-0.6.1.tar.gz 14:03:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:03:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:03:00 INFO - Installing collected packages: blobuploader, requests, docopt 14:03:00 INFO - Running setup.py install for blobuploader 14:03:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:03:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:03:00 INFO - Running setup.py install for requests 14:03:01 INFO - Running setup.py install for docopt 14:03:01 INFO - Successfully installed blobuploader requests docopt 14:03:01 INFO - Cleaning up... 14:03:01 INFO - Return code: 0 14:03:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:03: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')]} 14:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:03:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03: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')]} 14:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:03:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:01 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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:03:01 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 14:03:01 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 14:03:01 INFO - Using env: {'DISPLAY': ':0', 14:03:01 INFO - 'HOME': '/home/cltbld', 14:03:01 INFO - 'LANG': 'en_US.UTF-8', 14:03:01 INFO - 'LANGUAGE': 'en_US:en', 14:03:01 INFO - 'LOGNAME': 'cltbld', 14:03:01 INFO - 'MAIL': '/var/mail/cltbld', 14:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:03:01 INFO - 'MOZ_NO_REMOTE': '1', 14:03:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:03:01 INFO - 'NO_EM_RESTART': '1', 14:03:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:03:01 INFO - 'PWD': '/builds/slave/test-pgo', 14:03:01 INFO - 'SHELL': '/bin/bash', 14:03:01 INFO - 'SHLVL': '1', 14:03:01 INFO - 'TERM': 'linux', 14:03:01 INFO - 'TMOUT': '86400', 14:03:01 INFO - 'USER': 'cltbld', 14:03:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:03:01 INFO - '_': '/tools/buildbot/bin/python'} 14:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:03:01 INFO - Running setup.py (path:/tmp/pip-lHGsaW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:03:01 INFO - Running setup.py (path:/tmp/pip-CZ_k_K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:03:01 INFO - Running setup.py (path:/tmp/pip-37j6CQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:03:01 INFO - Running setup.py (path:/tmp/pip-BkAMsV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:03:01 INFO - Running setup.py (path:/tmp/pip-a4f09I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:03:01 INFO - Running setup.py (path:/tmp/pip-rD6u7S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:03:01 INFO - Running setup.py (path:/tmp/pip-SIQFLU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:03:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:03:01 INFO - Running setup.py (path:/tmp/pip-rOErGP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:03:02 INFO - Running setup.py (path:/tmp/pip-YJoWxl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:03:02 INFO - Running setup.py (path:/tmp/pip-vIibcK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:03:02 INFO - Running setup.py (path:/tmp/pip-Igx4BQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:03:02 INFO - Running setup.py (path:/tmp/pip-fXE6m0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:03:02 INFO - Running setup.py (path:/tmp/pip-QVcbsw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:03:02 INFO - Running setup.py (path:/tmp/pip-cI_uhL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:03:02 INFO - Running setup.py (path:/tmp/pip-2ve260-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:03:02 INFO - Running setup.py (path:/tmp/pip-XeAcXl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:03:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:03:02 INFO - Running setup.py (path:/tmp/pip-1XzIOw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:03:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:03:02 INFO - Running setup.py install for manifestparser 14:03:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:03:02 INFO - Running setup.py install for mozcrash 14:03:02 INFO - Running setup.py install for mozdebug 14:03:02 INFO - Running setup.py install for mozdevice 14:03:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Running setup.py install for mozfile 14:03:03 INFO - Running setup.py install for mozhttpd 14:03:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Running setup.py install for mozinfo 14:03:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Running setup.py install for mozInstall 14:03:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Running setup.py install for mozleak 14:03:03 INFO - Running setup.py install for mozlog 14:03:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Running setup.py install for moznetwork 14:03:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:03:03 INFO - Running setup.py install for mozprocess 14:03:03 INFO - Running setup.py install for mozprofile 14:03:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:03:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:03:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:03:04 INFO - Running setup.py install for mozrunner 14:03:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:03:04 INFO - Running setup.py install for mozscreenshot 14:03:04 INFO - Running setup.py install for moztest 14:03:04 INFO - Running setup.py install for mozversion 14:03:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:03:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:03:04 INFO - Cleaning up... 14:03:04 INFO - Return code: 0 14:03:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:03:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:03:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:04 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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:03:04 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 14:03:04 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 14:03:04 INFO - Using env: {'DISPLAY': ':0', 14:03:04 INFO - 'HOME': '/home/cltbld', 14:03:04 INFO - 'LANG': 'en_US.UTF-8', 14:03:04 INFO - 'LANGUAGE': 'en_US:en', 14:03:04 INFO - 'LOGNAME': 'cltbld', 14:03:04 INFO - 'MAIL': '/var/mail/cltbld', 14:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:03:04 INFO - 'MOZ_NO_REMOTE': '1', 14:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:03:04 INFO - 'NO_EM_RESTART': '1', 14:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:03:04 INFO - 'PWD': '/builds/slave/test-pgo', 14:03:04 INFO - 'SHELL': '/bin/bash', 14:03:04 INFO - 'SHLVL': '1', 14:03:04 INFO - 'TERM': 'linux', 14:03:04 INFO - 'TMOUT': '86400', 14:03:04 INFO - 'USER': 'cltbld', 14:03:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:03:04 INFO - '_': '/tools/buildbot/bin/python'} 14:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:03:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:03:04 INFO - Running setup.py (path:/tmp/pip-1n1nSU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:03:04 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)) 14:03:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:03:04 INFO - Running setup.py (path:/tmp/pip-LqMdbC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:03:04 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)) 14:03:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:03:04 INFO - Running setup.py (path:/tmp/pip-ZP1Nu5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:03:04 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)) 14:03:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:03:04 INFO - Running setup.py (path:/tmp/pip-ZlkxTA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:03:04 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)) 14:03:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:03:04 INFO - Running setup.py (path:/tmp/pip-RZSBGc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:03:05 INFO - Running setup.py (path:/tmp/pip-CNSQM6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:03:05 INFO - Running setup.py (path:/tmp/pip-cHRsit-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:03:05 INFO - Running setup.py (path:/tmp/pip-zHVrNg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:03:05 INFO - Running setup.py (path:/tmp/pip-UM_k0f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:03:05 INFO - Running setup.py (path:/tmp/pip-BT1D1Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:03:05 INFO - Running setup.py (path:/tmp/pip-2kvBmZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:03:05 INFO - Running setup.py (path:/tmp/pip-78TzKg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:03:05 INFO - Running setup.py (path:/tmp/pip-F5o9hf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:03:05 INFO - Running setup.py (path:/tmp/pip-e5_Eqe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:03:05 INFO - Running setup.py (path:/tmp/pip-MXlxT9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:03:05 INFO - Running setup.py (path:/tmp/pip-Sn9l3C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:03:05 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)) 14:03:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:03:05 INFO - Running setup.py (path:/tmp/pip-6Gtr1S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:03:05 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)) 14:03:05 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)) 14:03:05 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)) 14:03:05 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)) 14:03:05 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)) 14:03:05 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)) 14:03:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:03: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 14:03: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 14:03: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 14:03: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 14:03: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 14:03: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 14:03:07 INFO - Downloading blessings-1.5.1.tar.gz 14:03:07 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 14:03:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:03:07 INFO - Installing collected packages: blessings 14:03:07 INFO - Running setup.py install for blessings 14:03:07 INFO - Successfully installed blessings 14:03:07 INFO - Cleaning up... 14:03:07 INFO - Return code: 0 14:03:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:03:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:03:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:03:08 INFO - Reading from file tmpfile_stdout 14:03:08 INFO - Current package versions: 14:03:08 INFO - argparse == 1.2.1 14:03:08 INFO - blessings == 1.5.1 14:03:08 INFO - blobuploader == 1.2.4 14:03:08 INFO - docopt == 0.6.1 14:03:08 INFO - manifestparser == 1.1 14:03:08 INFO - mozInstall == 1.12 14:03:08 INFO - mozcrash == 0.16 14:03:08 INFO - mozdebug == 0.1 14:03:08 INFO - mozdevice == 0.46 14:03:08 INFO - mozfile == 1.2 14:03:08 INFO - mozhttpd == 0.7 14:03:08 INFO - mozinfo == 0.8 14:03:08 INFO - mozleak == 0.1 14:03:08 INFO - mozlog == 3.0 14:03:08 INFO - moznetwork == 0.27 14:03:08 INFO - mozprocess == 0.22 14:03:08 INFO - mozprofile == 0.27 14:03:08 INFO - mozrunner == 6.11 14:03:08 INFO - mozscreenshot == 0.1 14:03:08 INFO - mozsystemmonitor == 0.0 14:03:08 INFO - moztest == 0.7 14:03:08 INFO - mozversion == 1.4 14:03:08 INFO - psutil == 3.1.1 14:03:08 INFO - requests == 1.2.3 14:03:08 INFO - wsgiref == 0.1.2 14:03:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:08 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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:03:08 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 14:03:08 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 14:03:08 INFO - Using env: {'DISPLAY': ':0', 14:03:08 INFO - 'HOME': '/home/cltbld', 14:03:08 INFO - 'LANG': 'en_US.UTF-8', 14:03:08 INFO - 'LANGUAGE': 'en_US:en', 14:03:08 INFO - 'LOGNAME': 'cltbld', 14:03:08 INFO - 'MAIL': '/var/mail/cltbld', 14:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:03:08 INFO - 'MOZ_NO_REMOTE': '1', 14:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:03:08 INFO - 'NO_EM_RESTART': '1', 14:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:03:08 INFO - 'PWD': '/builds/slave/test-pgo', 14:03:08 INFO - 'SHELL': '/bin/bash', 14:03:08 INFO - 'SHLVL': '1', 14:03:08 INFO - 'TERM': 'linux', 14:03:08 INFO - 'TMOUT': '86400', 14:03:08 INFO - 'USER': 'cltbld', 14:03:08 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:03:08 INFO - '_': '/tools/buildbot/bin/python'} 14:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 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)) 14:03:08 INFO - Cleaning up... 14:03:08 INFO - Return code: 0 14:03:08 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:08 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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:03:08 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 14:03:08 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 14:03:08 INFO - Using env: {'DISPLAY': ':0', 14:03:08 INFO - 'HOME': '/home/cltbld', 14:03:08 INFO - 'LANG': 'en_US.UTF-8', 14:03:08 INFO - 'LANGUAGE': 'en_US:en', 14:03:08 INFO - 'LOGNAME': 'cltbld', 14:03:08 INFO - 'MAIL': '/var/mail/cltbld', 14:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:03:08 INFO - 'MOZ_NO_REMOTE': '1', 14:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:03:08 INFO - 'NO_EM_RESTART': '1', 14:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:03:08 INFO - 'PWD': '/builds/slave/test-pgo', 14:03:08 INFO - 'SHELL': '/bin/bash', 14:03:08 INFO - 'SHLVL': '1', 14:03:08 INFO - 'TERM': 'linux', 14:03:08 INFO - 'TMOUT': '86400', 14:03:08 INFO - 'USER': 'cltbld', 14:03:08 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:03:08 INFO - '_': '/tools/buildbot/bin/python'} 14:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:03:08 INFO - Downloading/unpacking jsonschema 14:03:08 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 14:03:08 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 14:03:08 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 14:03:08 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 14:03:08 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 14:03:08 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 14:03:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:03:10 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 14:03:10 INFO - Downloading/unpacking functools32 (from jsonschema) 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:03:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:03:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:03:10 INFO - warning: no files found matching '*.txt' 14:03:10 INFO - no previously-included directories found matching 'build' 14:03:10 INFO - no previously-included directories found matching 'dist' 14:03:10 INFO - no previously-included directories found matching '.git*' 14:03:10 INFO - Installing collected packages: jsonschema, functools32 14:03:10 INFO - Running setup.py install for functools32 14:03:10 INFO - warning: no files found matching '*.txt' 14:03:10 INFO - no previously-included directories found matching 'build' 14:03:10 INFO - no previously-included directories found matching 'dist' 14:03:10 INFO - no previously-included directories found matching '.git*' 14:03:10 INFO - Successfully installed jsonschema functools32 14:03:10 INFO - Cleaning up... 14:03:10 INFO - Return code: 0 14:03:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:03:10 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')]} 14:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:03:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:10 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')]} 14:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:03:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:03:10 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 0x7fefbb35ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, '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': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', '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 14:03:10 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 14:03:10 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 14:03:10 INFO - Using env: {'DISPLAY': ':0', 14:03:10 INFO - 'HOME': '/home/cltbld', 14:03:10 INFO - 'LANG': 'en_US.UTF-8', 14:03:10 INFO - 'LANGUAGE': 'en_US:en', 14:03:10 INFO - 'LOGNAME': 'cltbld', 14:03:10 INFO - 'MAIL': '/var/mail/cltbld', 14:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:03:10 INFO - 'MOZ_NO_REMOTE': '1', 14:03:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:03:10 INFO - 'NO_EM_RESTART': '1', 14:03:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:03:10 INFO - 'PWD': '/builds/slave/test-pgo', 14:03:10 INFO - 'SHELL': '/bin/bash', 14:03:10 INFO - 'SHLVL': '1', 14:03:10 INFO - 'TERM': 'linux', 14:03:10 INFO - 'TMOUT': '86400', 14:03:10 INFO - 'USER': 'cltbld', 14:03:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:03:10 INFO - '_': '/tools/buildbot/bin/python'} 14:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:03:10 INFO - Downloading/unpacking flake8 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 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 14:03:10 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 14:03:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:03:12 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 14:03:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:03:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:03:12 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 14:03:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:03:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:03:13 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 14:03:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:03: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 14:03: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 14:03: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 14:03: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 14:03: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 14:03: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 14:03:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:03:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:03:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:03:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:03:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:03:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:03:13 INFO - no previously-included directories found matching 'docs/_build' 14:03:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:03:13 INFO - Running setup.py install for pep8 14:03:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:03:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:03:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:03:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:03:13 INFO - no previously-included directories found matching 'docs/_build' 14:03:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:03:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:03:13 INFO - Cleaning up... 14:03:13 INFO - Return code: 0 14:03:13 INFO - Running post-action listener: _resource_record_post_action 14:03:13 INFO - Running post-action listener: _start_resource_monitoring 14:03:13 INFO - Starting resource monitoring. 14:03:13 INFO - ##### 14:03:13 INFO - ##### Running install step. 14:03:13 INFO - ##### 14:03:13 INFO - Running pre-action listener: _resource_record_pre_action 14:03:13 INFO - Running main action method: install 14:03:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:03:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:03:13 INFO - Reading from file tmpfile_stdout 14:03:13 INFO - Detecting whether we're running mozinstall >=1.0... 14:03:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:03:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:03:13 INFO - Reading from file tmpfile_stdout 14:03:13 INFO - Output received: 14:03:13 INFO - Usage: mozinstall [options] installer 14:03:13 INFO - Options: 14:03:13 INFO - -h, --help show this help message and exit 14:03:13 INFO - -d DEST, --destination=DEST 14:03:13 INFO - Directory to install application into. [default: 14:03:13 INFO - "/builds/slave/test-pgo"] 14:03:13 INFO - --app=APP Application being installed. [default: firefox] 14:03:13 INFO - mkdir: /builds/slave/test-pgo/build/application 14:03:13 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'] 14:03:13 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 14:03:28 INFO - Reading from file tmpfile_stdout 14:03:28 INFO - Output received: 14:03:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:03:28 INFO - Running post-action listener: _resource_record_post_action 14:03:28 INFO - ##### 14:03:28 INFO - ##### Running run-tests step. 14:03:28 INFO - ##### 14:03:28 INFO - Running pre-action listener: _resource_record_pre_action 14:03:28 INFO - Running main action method: run_tests 14:03:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:03:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:03:28 INFO - Python 2.7.3 14:03:28 INFO - Return code: 0 14:03:28 INFO - grabbing minidump binary from tooltool 14:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:28 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 0x1778350>, '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 0x1779250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17796e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:03:28 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 14:03:28 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 14:03:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:03:28 INFO - Return code: 0 14:03:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:03:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:03:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:03:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:03:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:03:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:03:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:03:29 INFO - Return code: 0 14:03:29 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/1452542499/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:03:29 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/1452542499/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 14:03:29 INFO - Using env: {'DISPLAY': ':0', 14:03:29 INFO - 'HOME': '/home/cltbld', 14:03:29 INFO - 'LANG': 'en_US.UTF-8', 14:03:29 INFO - 'LANGUAGE': 'en_US:en', 14:03:29 INFO - 'LOGNAME': 'cltbld', 14:03:29 INFO - 'MAIL': '/var/mail/cltbld', 14:03:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:03:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:03:29 INFO - 'MOZ_NO_REMOTE': '1', 14:03:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:03:29 INFO - 'NO_EM_RESTART': '1', 14:03:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:03:29 INFO - 'PWD': '/builds/slave/test-pgo', 14:03:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:03:29 INFO - 'SHELL': '/bin/bash', 14:03:29 INFO - 'SHLVL': '1', 14:03:29 INFO - 'TERM': 'linux', 14:03:29 INFO - 'TMOUT': '86400', 14:03:29 INFO - 'USER': 'cltbld', 14:03:29 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1452549649.447946-324553832', 14:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:03:29 INFO - '_': '/tools/buildbot/bin/python'} 14:03:29 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/1452542499/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 14:03:29 INFO - mozversion INFO | application_buildid: 20160111120139 14:03:29 INFO - mozversion INFO | application_changeset: 0488d79e6d74cfb4513ed65252c5104e1535e1ce 14:03:29 INFO - mozversion INFO | application_display_name: Nightly 14:03:29 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:03:29 INFO - mozversion INFO | application_name: Firefox 14:03:29 INFO - mozversion INFO | application_remotingname: firefox 14:03:29 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:03:29 INFO - mozversion INFO | application_vendor: Mozilla 14:03:29 INFO - mozversion INFO | application_version: 44.0 14:03:29 INFO - mozversion INFO | platform_buildid: 20160111120139 14:03:29 INFO - mozversion INFO | platform_changeset: 0488d79e6d74cfb4513ed65252c5104e1535e1ce 14:03:29 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:03:29 INFO - mozversion INFO | platform_version: 44.0 14:03:29 INFO - 2016-01-11 14:03:29,328 DEBUG : using testdate: 1452549809 14:03:29 INFO - 2016-01-11 14:03:29,328 DEBUG : actual date: 1452549809 14:03:29 INFO - 2016-01-11 14:03:29,337 INFO : starting webserver on 'localhost:41138' 14:03:29 INFO - 2016-01-11 14:03:29,338 INFO : Starting test suite talos-linux64-ix-056 14:03:29 INFO - 2016-01-11 14:03:29,338 INFO : Starting test dromaeo_css 14:03:29 INFO - 2016-01-11 14:03:29,339 DEBUG : operating with platform_type : linux_ 14:03:29 INFO - 2016-01-11 14:03:29,339 INFO : Initialising browser for dromaeo_css test... 14:03:29 INFO - 2016-01-11 14:03:29,356 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyV90i6/profile http://localhost:41138/getInfo.html 14:03:33 INFO - 2016-01-11 14:03:33,130 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:03:33 INFO - 2016-01-11 14:03:33,130 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:03:33 INFO - 2016-01-11 14:03:33,130 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:03:33 INFO - 2016-01-11 14:03:33,131 DEBUG : BROWSER_OUTPUT: __metrics 14:03:33 INFO - 2016-01-11 14:03:33,199 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] 14:03:34 INFO - 2016-01-11 14:03:34,461 INFO : Browser initialized. 14:03:34 INFO - 2016-01-11 14:03:34,462 INFO : Running cycle 1/1 for dromaeo_css test... 14:03:34 INFO - 2016-01-11 14:03:34,462 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyV90i6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:03:46 INFO - 2016-01-11 14:03:46,053 DEBUG : BROWSER_OUTPUT: RSS: Main: 127868928 14:03:46 INFO - 2016-01-11 14:03:46,053 DEBUG : BROWSER_OUTPUT: 14:04:36 INFO - 2016-01-11 14:04:36,894 DEBUG : BROWSER_OUTPUT: 1452549876890 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] 14:06:45 INFO - 2016-01-11 14:06:45,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41138/tests/dromaeo/cssquery-ext.html) 14:06:46 INFO - 2016-01-11 14:06:46,144 DEBUG : BROWSER_OUTPUT: RSS: Main: 242581504 14:06:46 INFO - 2016-01-11 14:06:46,144 DEBUG : BROWSER_OUTPUT: 14:09:48 INFO - 2016-01-11 14:09:48,193 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/dromaeo/cssquery-ext.html (next: http://localhost:41138/tests/dromaeo/cssquery-jquery.html) 14:09:48 INFO - 2016-01-11 14:09:48,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 218288128 14:09:48 INFO - 2016-01-11 14:09:48,759 DEBUG : BROWSER_OUTPUT: 14:12:50 INFO - 2016-01-11 14:12:50,449 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41138/tests/dromaeo/cssquery-mootools.html) 14:12:51 INFO - 2016-01-11 14:12:51,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 206708736 14:12:51 INFO - 2016-01-11 14:12:51,002 DEBUG : BROWSER_OUTPUT: 14:15:52 INFO - 2016-01-11 14:15:52,730 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41138/tests/dromaeo/cssquery-prototype.html) 14:15:53 INFO - 2016-01-11 14:15:53,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 192495616 14:15:53 INFO - 2016-01-11 14:15:53,304 DEBUG : BROWSER_OUTPUT: 14:18:55 INFO - 2016-01-11 14:18:55,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41138/tests/dromaeo/cssquery-yui.html) 14:18:56 INFO - 2016-01-11 14:18:56,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 193077248 14:18:56 INFO - 2016-01-11 14:18:56,263 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,230 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/dromaeo/cssquery-yui.html (next: http://localhost:41138/tests/dromaeo/cssquery-dojo.html) 14:21:58 INFO - 2016-01-11 14:21:58,481 DEBUG : BROWSER_OUTPUT: RSS: Main: 235622400 14:21:58 INFO - 2016-01-11 14:21:58,481 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,482 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:21:58 INFO - 2016-01-11 14:21:58,482 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:21:58 INFO - 2016-01-11 14:21:58,482 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:21:58 INFO - 2016-01-11 14:21:58,482 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:21:58 INFO - 2016-01-11 14:21:58,483 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3324.6176689680683;3373.881914133006;3400.2859399526096;3411.0790086676598;3427.7257819374445;5665.405132461094;5671.914921276174;5674.546036317095;5689.800856970007;5689.918915406075;5685.317761868571;5707.058335374658;5709.257796486461;5710.71446427678;5711.600187986832;5111.08000559899;5151.819686310989;5171.689698618076;5233.555147812441;5251.711155886424;5173.405058418273;5176.171812509999;5180.818671346504;5181.1192097343355;5186.429492755794;5077.187649976005;5109;5167.689938603696;5181.0933086709865;5203.947960520384;5086.79652813887;5160.56135228506;5197.324347834758;5210.97394513025;5211.088059589594;5157.871053223662;5188.9103288309125;5209.270702101709;5272.182811664198;5299.046171689094;5303.257543943851;5327.200919862052;5421.83734487966;5424.864378390533;5472.2338872557875;6178.073289006685;6180.3201647818705;6193.3496982816605;6193.442590166862;6197.101420294032;6223.844403889893;6227.034809604518;6230.283517395512;6237.625742455467;6237.937620623781;5505.504504594605;5607.186957891083;5634.239377684042;5713.457221563945;5744.798932037401;6217.533684973645;6218.533609979269;6237.688115594202;6244.469220116341;6251.249850018027;5771.826845194567;6065.059915713072;6072.757089716361;6074.726637301332;6079.787207447718;5994.790182343597;6019.578629495981;6096.177016102858;6101.969490152521;6125.326214116443;5991.9700401498585;6081.75672973085;6110.694465276719;6112;6119;5949.791757288474;6052.54605904559;6105.450509454082;6117.602355846856;6120.44915957566;4559.635229181657;4560.543945605413;4565.634749220055;4570.862874113782;4580.0610874771255;4086.2644723949975;4189.958100419018;4195.559466256061;4201.957980420218;4208.936865947013;5773.88452230953;5818.883622327615;5834.095715164156;5837.299524057055;5871.647701137946;5902.4097590240635;6072.301685306158;6075.605085669417;6143.262808463013;6151.907721384183;5673.319201695823;5696.145578163226;5774.971125144348;5785.190073389729;5805.970970145122;5271.841844744664;5366.812161574326;5386.5152136307925;5389.730513474311;5390.434004429479;6124.969375153096;6166.81499555014;6360.363963603695;6370.458511026614;6376;6098.420650038239;6187.16473276102;6198.349173336735;6207.6275423474735;6211.9068213977735;6192.566520343533;6194.442500174826;6194.628322300496;6195.721192546436;6200.100985357189;5811.854703632274;5912.142739302863;6011.398860113954;6036.63780173191;6077.4834139098675;6169.722362493609;6192.040233763774;6195.566310358412;6196.256449225942;6203.410675985774;6027.638341699512;6035.245594300713;6041.395860413923;6043.214382130295;6062.120992455981;5876.764929402776;5897;5987.700614969147;5994.100884867305;5999.940000599938;5951.226340575698;5958.523318134452;5965.34381218057;5966.343702192668;5966.880662386641;5983.730732116978;6056.878862422815;6057.666828324484;6062.575619706755;6066.302375226729;5666.886662266814;5726.312842458931;5770.826875193751;5894.793682221101;5905 14:21:58 INFO - 2016-01-11 14:21:58,484 DEBUG : BROWSER_OUTPUT: |1;ext.html;591.2638764737902;618.9504839612831;631.6368088349199;637.1939496536882;638.1926862518917;35104;36657.26685466289;38047.61952380479;38052.61947380525;38322.42516362257;39588.80205598968;40058;40127.39808902869;40249.396259056135;40368.59631403685;8315.584220788967;8449.957750211272;9027.638894444231;9227.815443691123;10454.26820122588;10679.359238445679;10795.83806242907;10801.189910756686;10811.783764324748;10818.513166907469;10829.187810914174;10929.617463388782;10932.672019839378;10941.671749847517;10951.507182176822;10767.784644307108;10842.295250808673;10848.457577121113;10851.51168197433;10872.402017888973;10645.574177032986;10764;10765.946170269177;10769.299995500267;10784.676459706221;43638.34542481865;44011.77994110041;44017.339739903924;44063;44076;3430.879919202816;3576.338377400189;3578.821058947068;3652.123490362294;3901.31726947783;33577.3284534308;34228;34254.48618270728;34256;34311.828440857884;3561.28774245152;3595.5505561804775;3619.1314084619503;3641.9802455312556;3653.7077033837495;316073;318201.40899295587;318364;318839;319028;64091.35908640947;65703.67148164324;67502;67875.66062169657;73122.6343868288;71208.64395677988;71473.64263178651;71662.64168679228;71791;71839.28160718431;16524.75212871808;16596.08721520328;16605;16621.916890415712;16665.750013749803;13175.802362964563;13315.334233288297;13317.467301307839;13330.666733331744;13332;22451.21420750266;22623.09507619677;22724.886375568018;22733.43166420853;22847.771522284896;8872.955635221782;8920.330975176888;9016.098390161063;9108.316876234308;9148.222401095982;845.2350622686479;944.9527523623791;947.2989987409268;967.7048500207363;1000.0449570660003;28504.572431413544;29341.413171736447;29345.559816602767;34970.47544286838;35611.4658280126;1022.672744721682;1066.9970227985668;1100.4387762241154;1147.1224513247205;1180.226951346869;13794.517191898436;14883.47907823221;14933.551993439996;15097.622559436102;15683.921580392025;455404;461328;462531;465193;465623;61895;62171.68914155491;62500.68749656223;62532;62538.374616254165;1426.5934208750457;1432.7134573085425;1444.6099553120598;1472.5287907869379;1562.8046494188227;32992.1751956203;33148.834255828566;33243.66756332406;33460.330793384484;34169.65830341665;51747.482525174266;52413.47586524086;52443;52471.73764131155;52498.21252681137;305879;310077;310486;310674;311082;36725.44911826271;37726.43410348792;37921;38012.61987380091;38030.80984595059;47585;48671;48932.2660160105;49027.26459103045;49584.75207623939;40937.795311023256;40940.18119637531;41721.37417938672;41734;42402.15195696007;41611.16777664491;41688.58311416847;41980.16039679128;42474.36288455738;42597.36103958381;40536.391954120125;40991;41547.37678934758;41580;41704;35916;36428;37211.06972325713;37659.62340376561;37941.430878536295;27856.303592409542;28163.295917602223;28232;28439.857800710863;28460 14:21:58 INFO - 2016-01-11 14:21:58,486 DEBUG : BROWSER_OUTPUT: |2;jquery.html;386.58442174662235;389.09535330356925;389.6755851346716;389.70966629860743;391.1453474158965;14844.406223751037;17648.823511764895;17728.911355443237;19233.903830480845;19239.807601923978;12800.615981520546;13143.605691829236;13280.269585172811;17778.733319000225;18191.363302284422;4428.490723566791;4935.950640493585;4941.530554597325;4941.654084214107;4941.802327906879;3857.787821669807;3969.2260009298197;4897.33885925399;5230.08473517133;5335.599830012745;5211.973940130294;5247.737613119329;5270.789168433258;5280.12877875152;5290.312259406291;5201.68789872607;5221.2429197766305;5343.786248550054;5351.2240725095035;5355.812546560891;4654;4654.976725116353;4656.627469802408;4664.5335466453425;4666;15283.694326113415;15664.608384790472;15883.205839708084;15902.443414480553;15902.60243493922;723.609251004457;725.7205975699341;726.1540305544034;1076.7631121153333;2260.6940920261404;11055.391953442557;11134.49894754738;11261.887381126166;11334.886651133465;11453.312801231952;1784.9914798139139;1798.2177752677628;1800;1818.1727314072066;1887.4054672778168;66489.66755166241;67849;68052;68477;69069.65465172642;7977.8404431912195;7997.520148790974;8005.519668819889;8024.398170137263;8046.356291496666;9552.665656701982;9554.904450955402;9558.761030974281;9569.37799043074;9570.56932438042;6222.657753823492;6331;6339;6363.459105975951;6363.872722545523;6534.117894084333;6544;6554.770583029571;6581.14445122131;6582.078509008743;3690.151265208963;3726.9627303726616;3728.1984373359314;3754.8310326035394;3755.586885442599;1271.9887689287038;1275.1392809853312;1298.3832679477362;1323.014354306029;1332.4403750424844;505.4667325971062;517.981870634526;524.2450870746113;527.3987654074358;533.2694208933708;11409.744928057908;12438.875611243953;12484.81272780909;12579.622611321674;12610.05424593141;498.63101305034013;518.693970557373;523.3222976245771;524.0514668450118;538.3405328472668;6611.834704132435;6918.654067296615;6942.340477654615;7029.3673569379;7105.147382314155;617484;633335;642779;666449;686238;75651.24348756552;76246.61876690692;77088;84771.15228847756;86685.566572168;545.1550097349093;560.341598621618;561.2731512690993;561.4021067563033;562.7158285066097;11272.549098035986;11588.94205528967;11758.82361764557;11798.587049453228;11995.760084798081;35655.64344356523;38105.80947095358;38866;39273;39818.40272395974;50301.74849125731;50646.24030639617;51628;52940.4705952951;53025.20462193147;7227.1327440707455;7357.852842943218;7366.337029667356;7368.6315684214505;7370.705171793283;12659.683507912376;12784.6803829905;12909.289989050692;12959.676008099874;13206.669833254246;10978.505967231653;11039.227254092137;11184.440777961232;11290.774184516427;11397.943010285228;10184;11080.667579972614;11084.778304434027;11178.0498657615;11257.549698011988;8804.479086617714;10344.482775861328;10726.946365268124;10800.24398292112;10807.35155890649;14101.506447274296;14449.349779259748;16451.506454806375;17141.22864471077;17364.218610162825;16330.836691632932;16341.428050018192;16373.508794736177;16495.83504164991;17142.314307427565 14:21:58 INFO - 2016-01-11 14:21:58,489 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1292.5928332575238;1293.9029572782044;1459.6934643724817;1847.8983655898924;1903.4955736729764;7175.712971481134;7333.046703928482;7634.427417943649;7712.46012779105;7794.805129871756;7836.9216307836905;8103.756887293376;8190.754277371673;8216.630251638675;8218.383621228402;2392.6889504364453;2427.538767634144;2432.5378178146098;2433.2335314376005;2433.9391515212174;2158.4495953531905;2206.0403724379867;2385.7733515316017;2437.183543512925;2445.99714117212;2385.522895420914;2394.114177754225;2395.4490467192554;2396.4727759892794;2407.9638805417844;2383.95232095358;2394.437307232799;2396.077510158584;2398.712154541449;2399.808015358767;2396.5686176488225;2436.2447641231274;2436.561418944589;2438.6829712137487;2440.633904914277;8221.79445513861;8287.668493260262;8305.044919834236;8310.750677479686;8317.085120636724;4978.253262010691;5025;5044.268581055726;5049.166887463564;5052.747362631853;3221.822799746013;3300.6369299377047;3389.2204792897774;3420.6066302375048;3422.982885085559;1065;1068.7808999155154;1083.4961742789562;1098.8461615373853;1141.1270378160714;11195.440227988649;11230.943845280803;11249.437528123643;11289.491972861159;11296.717582060513;4632.4672662644225;4646.047560250141;4708.246680531099;4709.270063140219;4710.199266124767;9496.430214187032;9559.139677428926;9559.283053770994;9559.90440095604;9589.90410095904;5074.4671809460215;5117.2068329409;5125.64120511568;5131.153359695609;5142.408623008327;4713.6936099153445;4719.079779442975;4720.079584481062;4723.7874295656775;4726.503717109654;3363.226457914693;4115.691323150776;4124.216398884202;4131.049858532554;4181.268278051329;1249.881261280177;1301.7591745527109;1329.8736620021082;1338.5248236875964;1357.5316515802033;822.3133683374449;898.7887846356101;916;916.5050872528893;1021.836506159011;7930.405219608552;7943.761687149395;7949.761507154794;7956.2441568050945;7973.083095444098;1222.9021678265717;1248.332142303863;1252.292454763056;1254.2975933477283;1272.0840994483956;6141.84645383869;6243.032329988859;6243.687815609202;6250.874982500415;6251.187345645037;9711.223102151811;9838.06538378853;9969.302148849656;9979.10188083076;9990.701208842804;9886.357386769982;9895.80208395828;9901.554430050664;9903.554340054714;9906.257030722725;7561.09266887977;7839.6472158752995;7866.488678236011;7874.842503149905;7875.6062196889925;7712;7892.960535197287;7938.206179382016;7953.2842044216295;7954.403419743659;8763.773071770085;8920.687775927812;8945.23965462943;8992.100789920956;9001.234895033995;8976.730698079067;9182.219511341611;9183.862242066241;9199.402038867453;9252.53737313145;7042.401395881408;7052.612106333996;7081.114860642419;7082.114735658043;7104.751333703295;7184.281571842983;7303.963480182565;7456.067991501062;7711.344535714303;7725.459217854696;7277.38142257914;7317.341439270491;7388.187299397169;7407.925920740724;7513.436492263211;7321.78034658961;7348.52234604749;7361.5583065014125;7425.183229844821;7452.143003554444;7352.48532602734;7425.925740742523;7426.554406735613;7432.3682486987045;7470.701171953278;6843.281455447106;6845.281245469353;6855.314468553105;6863.9656801717665;6876.759313424006;7131.928680713127;7141.714331426685;7156.105486814148;7172.641367931478;7194.244604316471 14:21:58 INFO - 2016-01-11 14:21:58,489 DEBUG : BROWSER_OUTPUT: |4;prototype.html;370.4794763357483;371.7212090931801;374.4383424862706;374.76577139287946;382.6097380671715;5755.798547050854;5934.732937017834;5941.257342832146;5944.494717948979;5967.373425790294;5767.106098554731;5876.853078673035;5881.206037184978;5896.351401345849;5923.229980102591;4607.976960115211;4609.03210325832;4627.23650597651;4627.514111018345;4633.235516139833;4463.843765468209;4541.841035563756;4565.2467342888385;4611.907761844761;4630.282306242538;4600.631949444037;4604.953950460503;4605.217113090765;4606.861794146181;4608.516105808875;4583.0604726031415;4600.470945841238;4603.010352774136;4615.261558150696;4619.468761092484;4098.7130900836955;4110.897227569305;4112.835486580563;4113.403556484293;4114.7119701620895;5595.4124816894455;5661.320641523043;5677.716114194273;5688.886222275572;5697.0599851024535;5394.541463975527;5439.156930675752;5443.8094666686475;5445.37378201504;5447.945520544784;6123.081537769326;6327.430531252209;6338.58799178052;6341.778037768656;6344.1435406220035;4516.706414083107;4529.4791099023705;4575.816967321303;4576.473705523841;4584.220682483986;6391.680415979229;6408.423241908251;6410.743570257184;6430.196225471816;6432.903506447407;4660.673752837269;4675.6727029108315;4678.60231880294;4679.532046795293;4685.9765701171955;4591.540845915449;4680.461746899082;4689.882752931136;4739.526047395302;4745.335653008581;5309.283246761715;5318.414974352818;5319.7340132993195;5322.520973112439;5324.520793128637;6292.433680968735;6294.0558916162945;6294.9685251574365;6296.118543403927;6297.149884765498;1674.6399524102376;1691.205133587212;1691.6785810695928;1692.339987404913;1692.5430133863788;1149.1955631058293;1150.804363258248;1155.7804017236867;1170.309517384747;1180.4215934192184;6191.071339299141;6193.628382296986;6200.751969921243;6204.224471941007;6208.1308616793685;6554.508411869034;6559.836004099936;6560.147180866456;6563.704633291513;6563.868722625521;6066.48434883031;6179.474744646665;6183.196184496077;6184.319724830265;6188.81433556994;3597.1906321078072;3614.963850361515;3619.2399596084592;3627.9818600907356;3632.6004139545157;6648.966755166193;6666.233383160901;6699.698513566901;6711.530192886451;6717.56335838169;6607.339266073354;6699.296573859675;6702.296258892746;6708.563943343667;6715.429188518933;6664.600123992479;6672.699728512229;6681.131452911188;6691.832704182434;6692.698828552727;6302.527310451643;6393.456556192776;6462.644554549545;6531.869362612816;6545.836354091186;6391.360863913757;6408.967955160194;6433.967830160819;6447.161868957193;6458.192725909262;6615;6633.800985970429;6645.468362531083;6653.401193892573;6653.9667301663185;4553.590176884097;4636.304554316879;4640.280756482751;4640.605548528278;4654.743989080633;6529.085927969993;6623.039659249285;6626.337366263335;6632.005199220155;6633.1376920999965;6422.743090276336;6426.582272152295;6437.291897891135;6445.613263204223;6456.031595260749;6313.96843015782;6418.550701450854;6423.96788016057;6432.935670643421;6436.13112229843;6319.96840015797;6323.747050118128;6328.715207815567;6334.113224148531;6338.176037115146;6452.580582262138;6455.386738259858;6457.289698133108;6459.967700161469;6469.805905822833;6104.511639068775;6205.751769929152;6218.875622487434;6252.874942501216;6270.811875643738 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: |5;yui.html;823.1644880446346;825.5088222507605;837.2255663511253;880.4497189256715;903.7108125399875;14494.927525362371;14625.414983400651;14743.11541307521;14822.703545929075;14844.33200505977;13414.597562073153;13512.32438378082;13543.119697219674;13576.796348054788;13598.18410895347;772.355083505271;779.8674225381699;919.1406035356963;929.488781170357;934.8691183234353;935.5369092299321;943.815955888602;945.2249155692335;947.4599478297373;947.9763005924873;816.824382757707;988.6342053440237;993.9701808945744;998.5905778630763;1001.1090129784461;814.5804910471112;971.1259866120478;983.2723784399565;986.1962500562091;989.4706332112321;737.4063878577742;738.2949283434307;738.3170567225349;738.6639079218943;738.6971341749856;14514.419423223058;14651.413943442249;14691.485797997018;14746.41014359435;14750.926245368812;11229.55081796727;12210.511579536807;12921.935390322988;13521.256330901797;14006.71986560263;10519.474026298654;10848.728781780439;10976.451177441097;10997.12023038163;11015.284006539548;3462.255615042778;3512.385332566816;3526.612072672004;3554.964450355489;3605.981970090159;45329.77335113303;45528;45801.31298030532;47572.5242747575;49193.50806491925;7682.924390585323;7739.690412383442;7908;7973;8003.199680032067;13378.397972091085;13398.732025359439;13400.463981440827;13416.932915335361;13424.664383390298;7027.8594428111155;7046.6828992695455;7123.287671232939;7433.331000210007;7457.925420745832;4225.57744225575;4478.104379124188;4587.449506059294;4600.240960241523;4641.234196357631;2961.1116665000413;2994.625671791026;2994.745446637017;2995.7004299569867;2996.5055765798834;460.7166592545627;467.4180645096876;474.4188369247644;477.1316204507832;477.48193210366446;546.4863028753039;557.9386267510571;563.3071322273627;565.7510695294058;660.3429587560312;7910.683572657029;7998.560079195584;8189.71336003249;8256.793580160544;8639.654413823502;562.9549636029108;575.8761866198704;647.6308504152682;656.9408753212139;671.2482020137477;3953.2686453006286;4596.149712303235;4599.701019433726;4623.260278355447;4627.190241707688;60420.39579604148;60686.69656651777;60734.69632651808;60817.08774368388;60862;54441;54539.45460545423;55069.44930550723;55326;55784.72107639436;51047;52208.73895630497;53475.19787203195;53572.464275357524;53628.73185634047;9256.25949924018;9339.159475647224;9504.382215155993;9514.429134251966;9597.664081757104;39338.2132357357;39436.2112757749;40107.39838902477;40620.79689601533;40975.1804963905;48477;50090;52242.47757522376;52381;52761;7657.617119144132;7965.402594805529;8014.559199244098;8095.473794203358;8161.755147345589;12312.384380780746;12580.370981451073;12599.622011339308;12668.936655316664;12744.235345878906;11266.66200014001;11554.364509952033;11935.462904169506;12141.453634586289;12324.014078873848;11759.823602646138;11956.163068585463;11979.880201198224;11992.280463172237;12162.513499459923;8843.51360675169;11455.770884582094;11488.253263537843;11546;11565.826512602149;9959.651412200537;10091.142252908585;10222.948885255526;10346.22403319739;10545.841812372668;10033.397996120257;10250.897491024994;10377.948110259702;10462.581496740046;10485.895141048491 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,842 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: __startTimestamp1452550918481__endTimestamp 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:21:58 INFO - 2016-01-11 14:21:58,491 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:21:58 INFO - 2016-01-11 14:21:58,492 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,492 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5665.35 Median:5897.00 stddev:642.73 (10.9%) stddev-sans-first:617.91 14:21:58 INFO - 2016-01-11 14:21:58,492 DEBUG : BROWSER_OUTPUT: Values: 3324.6 3373.9 3400.3 3411.1 3427.7 5665.4 5671.9 5674.5 5689.8 5689.9 5685.3 5707.1 5709.3 5710.7 5711.6 5111.1 5151.8 5171.7 5233.6 5251.7 5173.4 5176.2 5180.8 5181.1 5186.4 5077.2 5109.0 5167.7 5181.1 5203.9 5086.8 5160.6 5197.3 5211.0 5211.1 5157.9 5188.9 5209.3 5272.2 5299.0 5303.3 5327.2 5421.8 5424.9 5472.2 6178.1 6180.3 6193.3 6193.4 6197.1 6223.8 6227.0 6230.3 6237.6 6237.9 5505.5 5607.2 5634.2 5713.5 5744.8 6217.5 6218.5 6237.7 6244.5 6251.2 5771.8 6065.1 6072.8 6074.7 6079.8 5994.8 6019.6 6096.2 6102.0 6125.3 5992.0 6081.8 6110.7 6112.0 6119.0 5949.8 6052.5 6105.5 6117.6 6120.4 4559.6 4560.5 4565.6 4570.9 4580.1 4086.3 4190.0 4195.6 4202.0 4208.9 5773.9 5818.9 5834.1 5837.3 5871.6 5902.4 6072.3 6075.6 6143.3 6151.9 5673.3 5696.1 5775.0 5785.2 5806.0 5271.8 5366.8 5386.5 5389.7 5390.4 6125.0 6166.8 6360.4 6370.5 6376.0 6098.4 6187.2 6198.3 6207.6 6211.9 6192.6 6194.4 6194.6 6195.7 6200.1 5811.9 5912.1 6011.4 6036.6 6077.5 6169.7 6192.0 6195.6 6196.3 6203.4 6027.6 6035.2 6041.4 6043.2 6062.1 5876.8 5897.0 5987.7 5994.1 5999.9 5951.2 5958.5 5965.3 5966.3 5966.9 5983.7 6056.9 6057.7 6062.6 6066.3 5666.9 5726.3 5770.8 5894.8 5905.0 14:21:58 INFO - 2016-01-11 14:21:58,492 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,492 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:55022.92 Median:20034.99 stddev:97327.10 (485.8%) stddev-sans-first:97514.32 14:21:58 INFO - 2016-01-11 14:21:58,493 DEBUG : BROWSER_OUTPUT: Values: 591.3 619.0 631.6 637.2 638.2 35104.0 36657.3 38047.6 38052.6 38322.4 39588.8 40058.0 40127.4 40249.4 40368.6 8315.6 8450.0 9027.6 9227.8 10454.3 10679.4 10795.8 10801.2 10811.8 10818.5 10829.2 10929.6 10932.7 10941.7 10951.5 10767.8 10842.3 10848.5 10851.5 10872.4 10645.6 10764.0 10765.9 10769.3 10784.7 43638.3 44011.8 44017.3 44063.0 44076.0 3430.9 3576.3 3578.8 3652.1 3901.3 33577.3 34228.0 34254.5 34256.0 34311.8 3561.3 3595.6 3619.1 3642.0 3653.7 316073.0 318201.4 318364.0 318839.0 319028.0 64091.4 65703.7 67502.0 67875.7 73122.6 71208.6 71473.6 71662.6 71791.0 71839.3 16524.8 16596.1 16605.0 16621.9 16665.8 13175.8 13315.3 13317.5 13330.7 13332.0 22451.2 22623.1 22724.9 22733.4 22847.8 8873.0 8920.3 9016.1 9108.3 9148.2 845.2 945.0 947.3 967.7 1000.0 28504.6 29341.4 29345.6 34970.5 35611.5 1022.7 1067.0 1100.4 1147.1 1180.2 13794.5 14883.5 14933.6 15097.6 15683.9 455404.0 461328.0 462531.0 465193.0 465623.0 61895.0 62171.7 62500.7 62532.0 62538.4 1426.6 1432.7 1444.6 1472.5 1562.8 32992.2 33148.8 33243.7 33460.3 34169.7 51747.5 52413.5 52443.0 52471.7 52498.2 305879.0 310077.0 310486.0 310674.0 311082.0 36725.4 37726.4 37921.0 38012.6 38030.8 47585.0 48671.0 48932.3 49027.3 49584.8 40937.8 40940.2 41721.4 41734.0 42402.2 41611.2 41688.6 41980.2 42474.4 42597.4 40536.4 40991.0 41547.4 41580.0 41704.0 35916.0 36428.0 37211.1 37659.6 37941.4 27856.3 28163.3 28232.0 28439.9 28460.0 14:21:58 INFO - 2016-01-11 14:21:58,493 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,493 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31340.73 Median:4655.80 stddev:106324.68 (2283.7%) stddev-sans-first:106597.54 14:21:58 INFO - 2016-01-11 14:21:58,493 DEBUG : BROWSER_OUTPUT: Values: 386.6 389.1 389.7 389.7 391.1 14844.4 17648.8 17728.9 19233.9 19239.8 12800.6 13143.6 13280.3 17778.7 18191.4 4428.5 4936.0 4941.5 4941.7 4941.8 3857.8 3969.2 4897.3 5230.1 5335.6 5212.0 5247.7 5270.8 5280.1 5290.3 5201.7 5221.2 5343.8 5351.2 5355.8 4654.0 4655.0 4656.6 4664.5 4666.0 15283.7 15664.6 15883.2 15902.4 15902.6 723.6 725.7 726.2 1076.8 2260.7 11055.4 11134.5 11261.9 11334.9 11453.3 1785.0 1798.2 1800.0 1818.2 1887.4 66489.7 67849.0 68052.0 68477.0 69069.7 7977.8 7997.5 8005.5 8024.4 8046.4 9552.7 9554.9 9558.8 9569.4 9570.6 6222.7 6331.0 6339.0 6363.5 6363.9 6534.1 6544.0 6554.8 6581.1 6582.1 3690.2 3727.0 3728.2 3754.8 3755.6 1272.0 1275.1 1298.4 1323.0 1332.4 505.5 518.0 524.2 527.4 533.3 11409.7 12438.9 12484.8 12579.6 12610.1 498.6 518.7 523.3 524.1 538.3 6611.8 6918.7 6942.3 7029.4 7105.1 617484.0 633335.0 642779.0 666449.0 686238.0 75651.2 76246.6 77088.0 84771.2 86685.6 545.2 560.3 561.3 561.4 562.7 11272.5 11588.9 11758.8 11798.6 11995.8 35655.6 38105.8 38866.0 39273.0 39818.4 50301.7 50646.2 51628.0 52940.5 53025.2 7227.1 7357.9 7366.3 7368.6 7370.7 12659.7 12784.7 12909.3 12959.7 13206.7 10978.5 11039.2 11184.4 11290.8 11397.9 10184.0 11080.7 11084.8 11178.0 11257.5 8804.5 10344.5 10726.9 10800.2 10807.4 14101.5 14449.3 16451.5 17141.2 17364.2 16330.8 16341.4 16373.5 16495.8 17142.3 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5783.99 Median:6959.58 stddev:2976.57 (42.8%) stddev-sans-first:2965.77 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: Values: 1292.6 1293.9 1459.7 1847.9 1903.5 7175.7 7333.0 7634.4 7712.5 7794.8 7836.9 8103.8 8190.8 8216.6 8218.4 2392.7 2427.5 2432.5 2433.2 2433.9 2158.4 2206.0 2385.8 2437.2 2446.0 2385.5 2394.1 2395.4 2396.5 2408.0 2384.0 2394.4 2396.1 2398.7 2399.8 2396.6 2436.2 2436.6 2438.7 2440.6 8221.8 8287.7 8305.0 8310.8 8317.1 4978.3 5025.0 5044.3 5049.2 5052.7 3221.8 3300.6 3389.2 3420.6 3423.0 1065.0 1068.8 1083.5 1098.8 1141.1 11195.4 11230.9 11249.4 11289.5 11296.7 4632.5 4646.0 4708.2 4709.3 4710.2 9496.4 9559.1 9559.3 9559.9 9589.9 5074.5 5117.2 5125.6 5131.2 5142.4 4713.7 4719.1 4720.1 4723.8 4726.5 3363.2 4115.7 4124.2 4131.0 4181.3 1249.9 1301.8 1329.9 1338.5 1357.5 822.3 898.8 916.0 916.5 1021.8 7930.4 7943.8 7949.8 7956.2 7973.1 1222.9 1248.3 1252.3 1254.3 1272.1 6141.8 6243.0 6243.7 6250.9 6251.2 9711.2 9838.1 9969.3 9979.1 9990.7 9886.4 9895.8 9901.6 9903.6 9906.3 7561.1 7839.6 7866.5 7874.8 7875.6 7712.0 7893.0 7938.2 7953.3 7954.4 8763.8 8920.7 8945.2 8992.1 9001.2 8976.7 9182.2 9183.9 9199.4 9252.5 7042.4 7052.6 7081.1 7082.1 7104.8 7184.3 7304.0 7456.1 7711.3 7725.5 7277.4 7317.3 7388.2 7407.9 7513.4 7321.8 7348.5 7361.6 7425.2 7452.1 7352.5 7425.9 7426.6 7432.4 7470.7 6843.3 6845.3 6855.3 6864.0 6876.8 7131.9 7141.7 7156.1 7172.6 7194.2 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5334.30 Median:6085.50 stddev:1560.95 (25.7%) stddev-sans-first:1520.22 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: Values: 370.5 371.7 374.4 374.8 382.6 5755.8 5934.7 5941.3 5944.5 5967.4 5767.1 5876.9 5881.2 5896.4 5923.2 4608.0 4609.0 4627.2 4627.5 4633.2 4463.8 4541.8 4565.2 4611.9 4630.3 4600.6 4605.0 4605.2 4606.9 4608.5 4583.1 4600.5 4603.0 4615.3 4619.5 4098.7 4110.9 4112.8 4113.4 4114.7 5595.4 5661.3 5677.7 5688.9 5697.1 5394.5 5439.2 5443.8 5445.4 5447.9 6123.1 6327.4 6338.6 6341.8 6344.1 4516.7 4529.5 4575.8 4576.5 4584.2 6391.7 6408.4 6410.7 6430.2 6432.9 4660.7 4675.7 4678.6 4679.5 4686.0 4591.5 4680.5 4689.9 4739.5 4745.3 5309.3 5318.4 5319.7 5322.5 5324.5 6292.4 6294.1 6295.0 6296.1 6297.1 1674.6 1691.2 1691.7 1692.3 1692.5 1149.2 1150.8 1155.8 1170.3 1180.4 6191.1 6193.6 6200.8 6204.2 6208.1 6554.5 6559.8 6560.1 6563.7 6563.9 6066.5 6179.5 6183.2 6184.3 6188.8 3597.2 3615.0 3619.2 3628.0 3632.6 6649.0 6666.2 6699.7 6711.5 6717.6 6607.3 6699.3 6702.3 6708.6 6715.4 6664.6 6672.7 6681.1 6691.8 6692.7 6302.5 6393.5 6462.6 6531.9 6545.8 6391.4 6409.0 6434.0 6447.2 6458.2 6615.0 6633.8 6645.5 6653.4 6654.0 4553.6 4636.3 4640.3 4640.6 4654.7 6529.1 6623.0 6626.3 6632.0 6633.1 6422.7 6426.6 6437.3 6445.6 6456.0 6314.0 6418.6 6424.0 6432.9 6436.1 6320.0 6323.7 6328.7 6334.1 6338.2 6452.6 6455.4 6457.3 6460.0 6469.8 6104.5 6205.8 6218.9 6252.9 6270.8 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: 14:21:58 INFO - 2016-01-11 14:21:58,494 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14377.11 Median:24024.83 stddev:17309.52 (72.0%) stddev-sans-first:17328.16 14:21:58 INFO - 2016-01-11 14:21:58,495 DEBUG : BROWSER_OUTPUT: Values: 823.2 825.5 837.2 880.4 903.7 14494.9 14625.4 14743.1 14822.7 14844.3 13414.6 13512.3 13543.1 13576.8 13598.2 772.4 779.9 919.1 929.5 934.9 935.5 943.8 945.2 947.5 948.0 816.8 988.6 994.0 998.6 1001.1 814.6 971.1 983.3 986.2 989.5 737.4 738.3 738.3 738.7 738.7 14514.4 14651.4 14691.5 14746.4 14750.9 11229.6 12210.5 12921.9 13521.3 14006.7 10519.5 10848.7 10976.5 10997.1 11015.3 3462.3 3512.4 3526.6 3555.0 3606.0 45329.8 45528.0 45801.3 47572.5 49193.5 7682.9 7739.7 7908.0 7973.0 8003.2 13378.4 13398.7 13400.5 13416.9 13424.7 7027.9 7046.7 7123.3 7433.3 7457.9 4225.6 4478.1 4587.4 4600.2 4641.2 2961.1 2994.6 2994.7 2995.7 2996.5 460.7 467.4 474.4 477.1 477.5 546.5 557.9 563.3 565.8 660.3 7910.7 7998.6 8189.7 8256.8 8639.7 563.0 575.9 647.6 656.9 671.2 3953.3 4596.1 4599.7 4623.3 4627.2 60420.4 60686.7 60734.7 60817.1 60862.0 54441.0 54539.5 55069.4 55326.0 55784.7 51047.0 52208.7 53475.2 53572.5 53628.7 9256.3 9339.2 9504.4 9514.4 9597.7 39338.2 39436.2 40107.4 40620.8 40975.2 48477.0 50090.0 52242.5 52381.0 52761.0 7657.6 7965.4 8014.6 8095.5 8161.8 12312.4 12580.4 12599.6 12668.9 12744.2 11266.7 11554.4 11935.5 12141.5 12324.0 11759.8 11956.2 11979.9 11992.3 12162.5 8843.5 11455.8 11488.3 11546.0 11565.8 9959.7 10091.1 10222.9 10346.2 10545.8 10033.4 10250.9 10377.9 10462.6 10485.9 14:21:58 INFO - 2016-01-11 14:21:58,495 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:21:58 INFO - 2016-01-11 14:21:58,496 DEBUG : BROWSER_OUTPUT: 14:21:59 INFO - 2016-01-11 14:21:59,026 INFO : Browser exited with error code: 0 14:21:59 INFO - 2016-01-11 14:21:59,046 INFO : Completed test dromaeo_css (00:18:29) 14:21:59 INFO - 2016-01-11 14:21:59,047 INFO : Starting test kraken 14:21:59 INFO - 2016-01-11 14:21:59,047 DEBUG : operating with platform_type : linux_ 14:21:59 INFO - 2016-01-11 14:21:59,047 INFO : Initialising browser for kraken test... 14:21:59 INFO - 2016-01-11 14:21:59,053 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp2lVhCx/profile http://localhost:41138/getInfo.html 14:22:02 INFO - 2016-01-11 14:22:02,720 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:22:02 INFO - 2016-01-11 14:22:02,720 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:22:02 INFO - 2016-01-11 14:22:02,721 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:22:02 INFO - 2016-01-11 14:22:02,721 DEBUG : BROWSER_OUTPUT: __metrics 14:22:02 INFO - 2016-01-11 14:22:02,886 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] 14:22:04 INFO - 2016-01-11 14:22:04,097 INFO : Browser initialized. 14:22:04 INFO - 2016-01-11 14:22:04,098 INFO : Running cycle 1/1 for kraken test... 14:22:04 INFO - 2016-01-11 14:22:04,098 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp2lVhCx/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:22:15 INFO - 2016-01-11 14:22:15,676 DEBUG : BROWSER_OUTPUT: RSS: Main: 128847872 14:22:15 INFO - 2016-01-11 14:22:15,677 DEBUG : BROWSER_OUTPUT: 14:22:18 INFO - 2016-01-11 14:22:18,133 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41138/tests/kraken/driver.html?testName=audio-beat-detection) 14:22:18 INFO - 2016-01-11 14:22:18,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 161910784 14:22:18 INFO - 2016-01-11 14:22:18,696 DEBUG : BROWSER_OUTPUT: 14:22:21 INFO - 2016-01-11 14:22:21,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41138/tests/kraken/driver.html?testName=audio-dft) 14:22:21 INFO - 2016-01-11 14:22:21,853 DEBUG : BROWSER_OUTPUT: RSS: Main: 159490048 14:22:21 INFO - 2016-01-11 14:22:21,853 DEBUG : BROWSER_OUTPUT: 14:22:24 INFO - 2016-01-11 14:22:24,637 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41138/tests/kraken/driver.html?testName=audio-fft) 14:22:25 INFO - 2016-01-11 14:22:25,212 DEBUG : BROWSER_OUTPUT: RSS: Main: 155951104 14:22:25 INFO - 2016-01-11 14:22:25,212 DEBUG : BROWSER_OUTPUT: 14:22:27 INFO - 2016-01-11 14:22:27,190 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41138/tests/kraken/driver.html?testName=audio-oscillator) 14:22:27 INFO - 2016-01-11 14:22:27,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 156672000 14:22:27 INFO - 2016-01-11 14:22:27,759 DEBUG : BROWSER_OUTPUT: 14:22:29 INFO - 2016-01-11 14:22:29,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41138/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:22:30 INFO - 2016-01-11 14:22:30,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 154689536 14:22:30 INFO - 2016-01-11 14:22:30,478 DEBUG : BROWSER_OUTPUT: 14:22:33 INFO - 2016-01-11 14:22:33,342 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41138/tests/kraken/driver.html?testName=imaging-darkroom) 14:22:33 INFO - 2016-01-11 14:22:33,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 156254208 14:22:33 INFO - 2016-01-11 14:22:33,908 DEBUG : BROWSER_OUTPUT: 14:22:37 INFO - 2016-01-11 14:22:37,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41138/tests/kraken/driver.html?testName=imaging-desaturate) 14:22:37 INFO - 2016-01-11 14:22:37,872 DEBUG : BROWSER_OUTPUT: RSS: Main: 156360704 14:22:37 INFO - 2016-01-11 14:22:37,872 DEBUG : BROWSER_OUTPUT: 14:22:40 INFO - 2016-01-11 14:22:40,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41138/tests/kraken/driver.html?testName=json-parse-financial) 14:22:41 INFO - 2016-01-11 14:22:41,148 DEBUG : BROWSER_OUTPUT: RSS: Main: 156676096 14:22:41 INFO - 2016-01-11 14:22:41,148 DEBUG : BROWSER_OUTPUT: 14:22:42 INFO - 2016-01-11 14:22:42,662 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41138/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:22:43 INFO - 2016-01-11 14:22:43,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 161644544 14:22:43 INFO - 2016-01-11 14:22:43,224 DEBUG : BROWSER_OUTPUT: 14:22:44 INFO - 2016-01-11 14:22:44,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:22:45 INFO - 2016-01-11 14:22:45,548 DEBUG : BROWSER_OUTPUT: RSS: Main: 160505856 14:22:45 INFO - 2016-01-11 14:22:45,549 DEBUG : BROWSER_OUTPUT: 14:22:47 INFO - 2016-01-11 14:22:47,525 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:22:48 INFO - 2016-01-11 14:22:48,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 166244352 14:22:48 INFO - 2016-01-11 14:22:48,094 DEBUG : BROWSER_OUTPUT: 14:22:50 INFO - 2016-01-11 14:22:50,396 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:22:50 INFO - 2016-01-11 14:22:50,969 DEBUG : BROWSER_OUTPUT: RSS: Main: 167182336 14:22:50 INFO - 2016-01-11 14:22:50,969 DEBUG : BROWSER_OUTPUT: 14:22:53 INFO - 2016-01-11 14:22:53,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:22:53 INFO - 2016-01-11 14:22:53,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 165478400 14:22:53 INFO - 2016-01-11 14:22:53,943 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,578 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41138/tests/kraken/driver.html?testName=ai-astar) 14:22:55 INFO - 2016-01-11 14:22:55,828 DEBUG : BROWSER_OUTPUT: RSS: Main: 225599488 14:22:55 INFO - 2016-01-11 14:22:55,828 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,828 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:22:55 INFO - 2016-01-11 14:22:55,829 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:22:55 INFO - 2016-01-11 14:22:55,829 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:22:55 INFO - 2016-01-11 14:22:55,829 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:22:55 INFO - 2016-01-11 14:22:55,829 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;110;105;111;112;112;102;112;104;110 14:22:55 INFO - 2016-01-11 14:22:55,829 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;124;157;164;128;154;148;128;167;122 14:22:55 INFO - 2016-01-11 14:22:55,830 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;161;163;161;159;160;161;159;160 14:22:55 INFO - 2016-01-11 14:22:55,830 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;73;72;150;73;71;72;75;163;72 14:22:55 INFO - 2016-01-11 14:22:55,830 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;137;94;89;90;132;88;89;132 14:22:55 INFO - 2016-01-11 14:22:55,830 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;110;110;109;110;110 14:22:55 INFO - 2016-01-11 14:22:55,830 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;144;146;146;146;146;146 14:22:55 INFO - 2016-01-11 14:22:55,830 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;95;96;95;95 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;55;55;56;55;55;55;67;54;53 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;65;64;64;64;63;63;65;63;64 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;79;80;79;79;78;79;79;78;80 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;110;110;108;106;110;115;109;117;115;115 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;136;136;134;136;132;136;137;146;135 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;62;63;62;61;62;61;62;62;61 14:22:55 INFO - 2016-01-11 14:22:55,831 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:22:55 INFO - 2016-01-11 14:22:55,832 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:22:55 INFO - 2016-01-11 14:22:55,832 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,883 14:22:55 INFO - 2016-01-11 14:22:55,832 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:22:55 INFO - 2016-01-11 14:22:55,832 DEBUG : BROWSER_OUTPUT: __startTimestamp1452550975827__endTimestamp 14:22:55 INFO - 2016-01-11 14:22:55,832 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,835 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:22:55 INFO - 2016-01-11 14:22:55,836 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:22:55 INFO - 2016-01-11 14:22:55,836 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,836 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.90 Median:111.00 stddev:3.75 (3.4%) stddev-sans-first:3.91 14:22:55 INFO - 2016-01-11 14:22:55,836 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 105.0 111.0 112.0 112.0 102.0 112.0 104.0 110.0 14:22:55 INFO - 2016-01-11 14:22:55,836 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,836 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.90 Median:155.50 stddev:17.55 (11.3%) stddev-sans-first:18.06 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: Values: 157.0 124.0 157.0 164.0 128.0 154.0 148.0 128.0 167.0 122.0 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 161.0 163.0 161.0 159.0 160.0 161.0 159.0 160.0 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.20 Median:72.00 stddev:35.62 (49.5%) stddev-sans-first:37.17 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: Values: 71.0 73.0 72.0 150.0 73.0 71.0 72.0 75.0 163.0 72.0 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,837 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.10 Median:89.50 stddev:21.19 (23.7%) stddev-sans-first:21.94 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 137.0 94.0 89.0 90.0 132.0 88.0 89.0 132.0 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 110.0 110.0 109.0 110.0 110.0 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 144.0 146.0 146.0 146.0 146.0 146.0 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.90 Median:55.00 stddev:3.98 (7.2%) stddev-sans-first:4.17 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: Values: 54.0 55.0 55.0 56.0 55.0 55.0 55.0 67.0 54.0 53.0 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,838 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 64.0 64.0 64.0 63.0 63.0 65.0 63.0 64.0 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: Values: 79.0 79.0 80.0 79.0 79.0 78.0 79.0 79.0 78.0 80.0 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:111.50 Median:112.50 stddev:3.69 (3.3%) stddev-sans-first:3.87 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 108.0 106.0 110.0 115.0 109.0 117.0 115.0 115.0 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.70 Median:136.00 stddev:3.74 (2.8%) stddev-sans-first:3.88 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: Values: 139.0 136.0 136.0 134.0 136.0 132.0 136.0 137.0 146.0 135.0 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 63.0 62.0 61.0 62.0 61.0 62.0 62.0 61.0 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:22:55 INFO - 2016-01-11 14:22:55,839 DEBUG : BROWSER_OUTPUT: 14:22:56 INFO - 2016-01-11 14:22:56,490 INFO : Browser exited with error code: 0 14:22:56 INFO - 2016-01-11 14:22:56,548 INFO : Completed test kraken (00:00:57) 14:22:56 INFO - 2016-01-11 14:22:56,549 INFO : Starting test v8_7 14:22:56 INFO - 2016-01-11 14:22:56,549 DEBUG : operating with platform_type : linux_ 14:22:56 INFO - 2016-01-11 14:22:56,549 INFO : Initialising browser for v8_7 test... 14:22:56 INFO - 2016-01-11 14:22:56,555 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpcThHa2/profile http://localhost:41138/getInfo.html 14:23:00 INFO - 2016-01-11 14:23:00,256 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:23:00 INFO - 2016-01-11 14:23:00,256 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:23:00 INFO - 2016-01-11 14:23:00,256 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:23:00 INFO - 2016-01-11 14:23:00,256 DEBUG : BROWSER_OUTPUT: __metrics 14:23:00 INFO - 2016-01-11 14:23:00,438 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] 14:23:01 INFO - 2016-01-11 14:23:01,729 INFO : Browser initialized. 14:23:01 INFO - 2016-01-11 14:23:01,730 INFO : Running cycle 1/1 for v8_7 test... 14:23:01 INFO - 2016-01-11 14:23:01,730 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpcThHa2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:23:13 INFO - 2016-01-11 14:23:13,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 128528384 14:23:13 INFO - 2016-01-11 14:23:13,328 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,669 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41138/tests/v8_7/run.html (next: http://localhost:41138/tests/v8_7/run.html) 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: RSS: Main: 413544448 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:23:34 INFO - 2016-01-11 14:23:34,920 DEBUG : BROWSER_OUTPUT: |0;Richards;166.5001665001665 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.7067258449983 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.700638711469 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4975.124378109453 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.969696969697 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |5;Earley;563.6978579481398 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |7;RegExp;26179.48717948718 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |8;Splay;525.5474452554745 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:23:34 INFO - 2016-01-11 14:23:34,921 DEBUG : BROWSER_OUTPUT: __startTimestamp1452551014917__endTimestamp 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.50 Median:166.50 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: Values: 166.5 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.71 Median:170.71 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: Values: 170.7 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.70 Median:277.70 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: Values: 277.7 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4975.12 Median:4975.12 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: Values: 4975.1 14:23:34 INFO - 2016-01-11 14:23:34,922 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.97 Median:946.97 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: Values: 947.0 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:563.70 Median:563.70 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: Values: 563.7 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,923 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: Values: 10526.3 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26179.49 Median:26179.49 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: Values: 26179.5 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:525.55 Median:525.55 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: Values: 525.5 14:23:34 INFO - 2016-01-11 14:23:34,924 DEBUG : BROWSER_OUTPUT: 14:23:34 INFO - 2016-01-11 14:23:34,925 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 14:23:34 INFO - 2016-01-11 14:23:34,925 DEBUG : BROWSER_OUTPUT: Values: 4817.3 14:23:34 INFO - 2016-01-11 14:23:34,925 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:23:34 INFO - 2016-01-11 14:23:34,925 DEBUG : BROWSER_OUTPUT: 14:23:35 INFO - 2016-01-11 14:23:35,673 INFO : Browser exited with error code: 0 14:23:35 INFO - 2016-01-11 14:23:35,690 INFO : Completed test v8_7 (00:00:39) 14:23:35 INFO - 2016-01-11 14:23:35,982 INFO : Completed test suite (00:20:06) 14:23:35 INFO - 2016-01-11 14:23:35,982 DEBUG : Working with test: dromaeo_css 14:23:35 INFO - 2016-01-11 14:23:35,982 DEBUG : Generating results file: dromaeo_css 14:23:35 INFO - 2016-01-11 14:23:35,984 DEBUG : Working with test: kraken 14:23:35 INFO - 2016-01-11 14:23:35,984 DEBUG : Generating results file: kraken 14:23:35 INFO - 2016-01-11 14:23:35,985 INFO : javascript benchmark 14:23:35 INFO - 2016-01-11 14:23:35,985 DEBUG : Working with test: v8_7 14:23:35 INFO - 2016-01-11 14:23:35,985 DEBUG : Generating results file: v8_7 14:23:35 INFO - 2016-01-11 14:23:35,985 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:23:36 INFO - 2016-01-11 14:23:36,040 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:23:36 INFO - 2016-01-11 14:23:36,041 DEBUG : process_Request line: dromaeo_css 7124.28 graph.html#tests=[[72,53,35]] 14:23:36 INFO - 2016-01-11 14:23:36,041 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:23:36 INFO - 2016-01-11 14:23:36,088 DEBUG : process_Request line: kraken 1465.70 graph.html#tests=[[232,53,35]] 14:23:36 INFO - 2016-01-11 14:23:36,088 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:23:36 INFO - 2016-01-11 14:23:36,120 DEBUG : process_Request line: v8_7 23063.88 graph.html#tests=[[230,53,35]] 14:23:36 INFO - 2016-01-11 14:23:36,121 INFO : javascript benchmark 14:23:36 INFO - 2016-01-11 14:23:36,125 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3324.6176689680683, 3373.881914133006, 3400.2859399526096, 3411.0790086676598, 3427.7257819374445, 5665.405132461094, 5671.914921276174, 5674.546036317095, 5689.800856970007, 5689.918915406075, 5685.317761868571, 5707.058335374658, 5709.257796486461, 5710.71446427678, 5711.600187986832, 5111.08000559899, 5151.819686310989, 5171.689698618076, 5233.555147812441, 5251.711155886424, 5173.405058418273, 5176.171812509999, 5180.818671346504, 5181.1192097343355, 5186.429492755794, 5077.187649976005, 5109.0, 5167.689938603696, 5181.0933086709865, 5203.947960520384, 5086.79652813887, 5160.56135228506, 5197.324347834758, 5210.97394513025, 5211.088059589594, 5157.871053223662, 5188.9103288309125, 5209.270702101709, 5272.182811664198, 5299.046171689094, 5303.257543943851, 5327.200919862052, 5421.83734487966, 5424.864378390533, 5472.2338872557875, 6178.073289006685, 6180.3201647818705, 6193.3496982816605, 6193.442590166862, 6197.101420294032, 6223.844403889893, 6227.034809604518, 6230.283517395512, 6237.625742455467, 6237.937620623781, 5505.504504594605, 5607.186957891083, 5634.239377684042, 5713.457221563945, 5744.798932037401, 6217.533684973645, 6218.533609979269, 6237.688115594202, 6244.469220116341, 6251.249850018027, 5771.826845194567, 6065.059915713072, 6072.757089716361, 6074.726637301332, 6079.787207447718, 5994.790182343597, 6019.578629495981, 6096.177016102858, 6101.969490152521, 6125.326214116443, 5991.9700401498585, 6081.75672973085, 6110.694465276719, 6112.0, 6119.0, 5949.791757288474, 6052.54605904559, 6105.450509454082, 6117.602355846856, 6120.44915957566, 4559.635229181657, 4560.543945605413, 4565.634749220055, 4570.862874113782, 4580.0610874771255, 4086.2644723949975, 4189.958100419018, 4195.559466256061, 4201.957980420218, 4208.936865947013, 5773.88452230953, 5818.883622327615, 5834.095715164156, 5837.299524057055, 5871.647701137946, 5902.4097590240635, 6072.301685306158, 6075.605085669417, 6143.262808463013, 6151.907721384183, 5673.319201695823, 5696.145578163226, 5774.971125144348, 5785.190073389729, 5805.970970145122, 5271.841844744664, 5366.812161574326, 5386.5152136307925, 5389.730513474311, 5390.434004429479, 6124.969375153096, 6166.81499555014, 6360.363963603695, 6370.458511026614, 6376.0, 6098.420650038239, 6187.16473276102, 6198.349173336735, 6207.6275423474735, 6211.9068213977735, 6192.566520343533, 6194.442500174826, 6194.628322300496, 6195.721192546436, 6200.100985357189, 5811.854703632274, 5912.142739302863, 6011.398860113954, 6036.63780173191, 6077.4834139098675, 6169.722362493609, 6192.040233763774, 6195.566310358412, 6196.256449225942, 6203.410675985774, 6027.638341699512, 6035.245594300713, 6041.395860413923, 6043.214382130295, 6062.120992455981, 5876.764929402776, 5897.0, 5987.700614969147, 5994.100884867305, 5999.940000599938, 5951.226340575698, 5958.523318134452, 5965.34381218057, 5966.343702192668, 5966.880662386641, 5983.730732116978, 6056.878862422815, 6057.666828324484, 6062.575619706755, 6066.302375226729, 5666.886662266814, 5726.312842458931, 5770.826875193751, 5894.793682221101, 5905.0], "unit": "ms", "name": "dojo.html", "value": 5622.656943382554}, {"lowerIsBetter": false, "replicates": [591.2638764737902, 618.9504839612831, 631.6368088349199, 637.1939496536882, 638.1926862518917, 35104.0, 36657.26685466289, 38047.61952380479, 38052.61947380525, 38322.42516362257, 39588.80205598968, 40058.0, 40127.39808902869, 40249.396259056135, 40368.59631403685, 8315.584220788967, 8449.957750211272, 9027.638894444231, 9227.815443691123, 10454.26820122588, 10679.359238445679, 10795.83806242907, 10801.189910756686, 10811.783764324748, 10818.513166907469, 10829.187810914174, 10929.617463388782, 10932.672019839378, 10941.671749847517, 10951.507182176822, 10767.784644307108, 10842.295250808673, 10848.457577121113, 10851.51168197433, 10872.402017888973, 10645.574177032986, 10764.0, 10765.946170269177, 10769.299995500267, 10784.676459706221, 43638.34542481865, 44011.77994110041, 44017.339739903924, 44063.0, 44076.0, 3430.879919202816, 3576.338377400189, 3578.821058947068, 3652.123490362294, 3901.31726947783, 33577.3284534308, 34228.0, 34254.48618270728, 34256.0, 34311.828440857884, 3561.28774245152, 3595.5505561804775, 3619.1314084619503, 3641.9802455312556, 3653.7077033837495, 316073.0, 318201.40899295587, 318364.0, 318839.0, 319028.0, 64091.35908640947, 65703.67148164324, 67502.0, 67875.66062169657, 73122.6343868288, 71208.64395677988, 71473.64263178651, 71662.64168679228, 71791.0, 71839.28160718431, 16524.75212871808, 16596.08721520328, 16605.0, 16621.916890415712, 16665.750013749803, 13175.802362964563, 13315.334233288297, 13317.467301307839, 13330.666733331744, 13332.0, 22451.21420750266, 22623.09507619677, 22724.886375568018, 22733.43166420853, 22847.771522284896, 8872.955635221782, 8920.330975176888, 9016.098390161063, 9108.316876234308, 9148.222401095982, 845.2350622686479, 944.9527523623791, 947.2989987409268, 967.7048500207363, 1000.0449570660003, 28504.572431413544, 29341.413171736447, 29345.559816602767, 34970.47544286838, 35611.4658280126, 1022.672744721682, 1066.9970227985668, 1100.4387762241154, 1147.1224513247205, 1180.226951346869, 13794.517191898436, 14883.47907823221, 14933.551993439996, 15097.622559436102, 15683.921580392025, 455404.0, 461328.0, 462531.0, 465193.0, 465623.0, 61895.0, 62171.68914155491, 62500.68749656223, 62532.0, 62538.374616254165, 1426.5934208750457, 1432.7134573085425, 1444.6099553120598, 1472.5287907869379, 1562.8046494188227, 32992.1751956203, 33148.834255828566, 33243.66756332406, 33460.330793384484, 34169.65830341665, 51747.482525174266, 52413.47586524086, 52443.0, 52471.73764131155, 52498.21252681137, 305879.0, 310077.0, 310486.0, 310674.0, 311082.0, 36725.44911826271, 37726.43410348792, 37921.0, 38012.61987380091, 38030.80984595059, 47585.0, 48671.0, 48932.2660160105, 49027.26459103045, 49584.75207623939, 40937.795311023256, 40940.18119637531, 41721.37417938672, 41734.0, 42402.15195696007, 41611.16777664491, 41688.58311416847, 41980.16039679128, 42474.36288455738, 42597.36103958381, 40536.391954120125, 40991.0, 41547.37678934758, 41580.0, 41704.0, 35916.0, 36428.0, 37211.06972325713, 37659.62340376561, 37941.430878536295, 27856.303592409542, 28163.295917602223, 28232.0, 28439.857800710863, 28460.0], "unit": "ms", "name": "ext.html", "value": 20721.447157477938}, {"lowerIsBetter": false, "replicates": [386.58442174662235, 389.09535330356925, 389.6755851346716, 389.70966629860743, 391.1453474158965, 14844.406223751037, 17648.823511764895, 17728.911355443237, 19233.903830480845, 19239.807601923978, 12800.615981520546, 13143.605691829236, 13280.269585172811, 17778.733319000225, 18191.363302284422, 4428.490723566791, 4935.950640493585, 4941.530554597325, 4941.654084214107, 4941.802327906879, 3857.787821669807, 3969.2260009298197, 4897.33885925399, 5230.08473517133, 5335.599830012745, 5211.973940130294, 5247.737613119329, 5270.789168433258, 5280.12877875152, 5290.312259406291, 5201.68789872607, 5221.2429197766305, 5343.786248550054, 5351.2240725095035, 5355.812546560891, 4654.0, 4654.976725116353, 4656.627469802408, 4664.5335466453425, 4666.0, 15283.694326113415, 15664.608384790472, 15883.205839708084, 15902.443414480553, 15902.60243493922, 723.609251004457, 725.7205975699341, 726.1540305544034, 1076.7631121153333, 2260.6940920261404, 11055.391953442557, 11134.49894754738, 11261.887381126166, 11334.886651133465, 11453.312801231952, 1784.9914798139139, 1798.2177752677628, 1800.0, 1818.1727314072066, 1887.4054672778168, 66489.66755166241, 67849.0, 68052.0, 68477.0, 69069.65465172642, 7977.8404431912195, 7997.520148790974, 8005.519668819889, 8024.398170137263, 8046.356291496666, 9552.665656701982, 9554.904450955402, 9558.761030974281, 9569.37799043074, 9570.56932438042, 6222.657753823492, 6331.0, 6339.0, 6363.459105975951, 6363.872722545523, 6534.117894084333, 6544.0, 6554.770583029571, 6581.14445122131, 6582.078509008743, 3690.151265208963, 3726.9627303726616, 3728.1984373359314, 3754.8310326035394, 3755.586885442599, 1271.9887689287038, 1275.1392809853312, 1298.3832679477362, 1323.014354306029, 1332.4403750424844, 505.4667325971062, 517.981870634526, 524.2450870746113, 527.3987654074358, 533.2694208933708, 11409.744928057908, 12438.875611243953, 12484.81272780909, 12579.622611321674, 12610.05424593141, 498.63101305034013, 518.693970557373, 523.3222976245771, 524.0514668450118, 538.3405328472668, 6611.834704132435, 6918.654067296615, 6942.340477654615, 7029.3673569379, 7105.147382314155, 617484.0, 633335.0, 642779.0, 666449.0, 686238.0, 75651.24348756552, 76246.61876690692, 77088.0, 84771.15228847756, 86685.566572168, 545.1550097349093, 560.341598621618, 561.2731512690993, 561.4021067563033, 562.7158285066097, 11272.549098035986, 11588.94205528967, 11758.82361764557, 11798.587049453228, 11995.760084798081, 35655.64344356523, 38105.80947095358, 38866.0, 39273.0, 39818.40272395974, 50301.74849125731, 50646.24030639617, 51628.0, 52940.4705952951, 53025.20462193147, 7227.1327440707455, 7357.852842943218, 7366.337029667356, 7368.6315684214505, 7370.705171793283, 12659.683507912376, 12784.6803829905, 12909.289989050692, 12959.676008099874, 13206.669833254246, 10978.505967231653, 11039.227254092137, 11184.440777961232, 11290.774184516427, 11397.943010285228, 10184.0, 11080.667579972614, 11084.778304434027, 11178.0498657615, 11257.549698011988, 8804.479086617714, 10344.482775861328, 10726.946365268124, 10800.24398292112, 10807.35155890649, 14101.506447274296, 14449.349779259748, 16451.506454806375, 17141.22864471077, 17364.218610162825, 16330.836691632932, 16341.428050018192, 16373.508794736177, 16495.83504164991, 17142.314307427565], "unit": "ms", "name": "jquery.html", "value": 7617.3171258298535}, {"lowerIsBetter": false, "replicates": [1292.5928332575238, 1293.9029572782044, 1459.6934643724817, 1847.8983655898924, 1903.4955736729764, 7175.712971481134, 7333.046703928482, 7634.427417943649, 7712.46012779105, 7794.805129871756, 7836.9216307836905, 8103.756887293376, 8190.754277371673, 8216.630251638675, 8218.383621228402, 2392.6889504364453, 2427.538767634144, 2432.5378178146098, 2433.2335314376005, 2433.9391515212174, 2158.4495953531905, 2206.0403724379867, 2385.7733515316017, 2437.183543512925, 2445.99714117212, 2385.522895420914, 2394.114177754225, 2395.4490467192554, 2396.4727759892794, 2407.9638805417844, 2383.95232095358, 2394.437307232799, 2396.077510158584, 2398.712154541449, 2399.808015358767, 2396.5686176488225, 2436.2447641231274, 2436.561418944589, 2438.6829712137487, 2440.633904914277, 8221.79445513861, 8287.668493260262, 8305.044919834236, 8310.750677479686, 8317.085120636724, 4978.253262010691, 5025.0, 5044.268581055726, 5049.166887463564, 5052.747362631853, 3221.822799746013, 3300.6369299377047, 3389.2204792897774, 3420.6066302375048, 3422.982885085559, 1065.0, 1068.7808999155154, 1083.4961742789562, 1098.8461615373853, 1141.1270378160714, 11195.440227988649, 11230.943845280803, 11249.437528123643, 11289.491972861159, 11296.717582060513, 4632.4672662644225, 4646.047560250141, 4708.246680531099, 4709.270063140219, 4710.199266124767, 9496.430214187032, 9559.139677428926, 9559.283053770994, 9559.90440095604, 9589.90410095904, 5074.4671809460215, 5117.2068329409, 5125.64120511568, 5131.153359695609, 5142.408623008327, 4713.6936099153445, 4719.079779442975, 4720.079584481062, 4723.7874295656775, 4726.503717109654, 3363.226457914693, 4115.691323150776, 4124.216398884202, 4131.049858532554, 4181.268278051329, 1249.881261280177, 1301.7591745527109, 1329.8736620021082, 1338.5248236875964, 1357.5316515802033, 822.3133683374449, 898.7887846356101, 916.0, 916.5050872528893, 1021.836506159011, 7930.405219608552, 7943.761687149395, 7949.761507154794, 7956.2441568050945, 7973.083095444098, 1222.9021678265717, 1248.332142303863, 1252.292454763056, 1254.2975933477283, 1272.0840994483956, 6141.84645383869, 6243.032329988859, 6243.687815609202, 6250.874982500415, 6251.187345645037, 9711.223102151811, 9838.06538378853, 9969.302148849656, 9979.10188083076, 9990.701208842804, 9886.357386769982, 9895.80208395828, 9901.554430050664, 9903.554340054714, 9906.257030722725, 7561.09266887977, 7839.6472158752995, 7866.488678236011, 7874.842503149905, 7875.6062196889925, 7712.0, 7892.960535197287, 7938.206179382016, 7953.2842044216295, 7954.403419743659, 8763.773071770085, 8920.687775927812, 8945.23965462943, 8992.100789920956, 9001.234895033995, 8976.730698079067, 9182.219511341611, 9183.862242066241, 9199.402038867453, 9252.53737313145, 7042.401395881408, 7052.612106333996, 7081.114860642419, 7082.114735658043, 7104.751333703295, 7184.281571842983, 7303.963480182565, 7456.067991501062, 7711.344535714303, 7725.459217854696, 7277.38142257914, 7317.341439270491, 7388.187299397169, 7407.925920740724, 7513.436492263211, 7321.78034658961, 7348.52234604749, 7361.5583065014125, 7425.183229844821, 7452.143003554444, 7352.48532602734, 7425.925740742523, 7426.554406735613, 7432.3682486987045, 7470.701171953278, 6843.281455447106, 6845.281245469353, 6855.314468553105, 6863.9656801717665, 6876.759313424006, 7131.928680713127, 7141.714331426685, 7156.105486814148, 7172.641367931478, 7194.244604316471], "unit": "ms", "name": "mootools.html", "value": 4740.591385458101}, {"lowerIsBetter": false, "replicates": [370.4794763357483, 371.7212090931801, 374.4383424862706, 374.76577139287946, 382.6097380671715, 5755.798547050854, 5934.732937017834, 5941.257342832146, 5944.494717948979, 5967.373425790294, 5767.106098554731, 5876.853078673035, 5881.206037184978, 5896.351401345849, 5923.229980102591, 4607.976960115211, 4609.03210325832, 4627.23650597651, 4627.514111018345, 4633.235516139833, 4463.843765468209, 4541.841035563756, 4565.2467342888385, 4611.907761844761, 4630.282306242538, 4600.631949444037, 4604.953950460503, 4605.217113090765, 4606.861794146181, 4608.516105808875, 4583.0604726031415, 4600.470945841238, 4603.010352774136, 4615.261558150696, 4619.468761092484, 4098.7130900836955, 4110.897227569305, 4112.835486580563, 4113.403556484293, 4114.7119701620895, 5595.4124816894455, 5661.320641523043, 5677.716114194273, 5688.886222275572, 5697.0599851024535, 5394.541463975527, 5439.156930675752, 5443.8094666686475, 5445.37378201504, 5447.945520544784, 6123.081537769326, 6327.430531252209, 6338.58799178052, 6341.778037768656, 6344.1435406220035, 4516.706414083107, 4529.4791099023705, 4575.816967321303, 4576.473705523841, 4584.220682483986, 6391.680415979229, 6408.423241908251, 6410.743570257184, 6430.196225471816, 6432.903506447407, 4660.673752837269, 4675.6727029108315, 4678.60231880294, 4679.532046795293, 4685.9765701171955, 4591.540845915449, 4680.461746899082, 4689.882752931136, 4739.526047395302, 4745.335653008581, 5309.283246761715, 5318.414974352818, 5319.7340132993195, 5322.520973112439, 5324.520793128637, 6292.433680968735, 6294.0558916162945, 6294.9685251574365, 6296.118543403927, 6297.149884765498, 1674.6399524102376, 1691.205133587212, 1691.6785810695928, 1692.339987404913, 1692.5430133863788, 1149.1955631058293, 1150.804363258248, 1155.7804017236867, 1170.309517384747, 1180.4215934192184, 6191.071339299141, 6193.628382296986, 6200.751969921243, 6204.224471941007, 6208.1308616793685, 6554.508411869034, 6559.836004099936, 6560.147180866456, 6563.704633291513, 6563.868722625521, 6066.48434883031, 6179.474744646665, 6183.196184496077, 6184.319724830265, 6188.81433556994, 3597.1906321078072, 3614.963850361515, 3619.2399596084592, 3627.9818600907356, 3632.6004139545157, 6648.966755166193, 6666.233383160901, 6699.698513566901, 6711.530192886451, 6717.56335838169, 6607.339266073354, 6699.296573859675, 6702.296258892746, 6708.563943343667, 6715.429188518933, 6664.600123992479, 6672.699728512229, 6681.131452911188, 6691.832704182434, 6692.698828552727, 6302.527310451643, 6393.456556192776, 6462.644554549545, 6531.869362612816, 6545.836354091186, 6391.360863913757, 6408.967955160194, 6433.967830160819, 6447.161868957193, 6458.192725909262, 6615.0, 6633.800985970429, 6645.468362531083, 6653.401193892573, 6653.9667301663185, 4553.590176884097, 4636.304554316879, 4640.280756482751, 4640.605548528278, 4654.743989080633, 6529.085927969993, 6623.039659249285, 6626.337366263335, 6632.005199220155, 6633.1376920999965, 6422.743090276336, 6426.582272152295, 6437.291897891135, 6445.613263204223, 6456.031595260749, 6313.96843015782, 6418.550701450854, 6423.96788016057, 6432.935670643421, 6436.13112229843, 6319.96840015797, 6323.747050118128, 6328.715207815567, 6334.113224148531, 6338.176037115146, 6452.580582262138, 6455.386738259858, 6457.289698133108, 6459.967700161469, 6469.805905822833, 6104.511639068775, 6205.751769929152, 6218.875622487434, 6252.874942501216, 6270.811875643738], "unit": "ms", "name": "prototype.html", "value": 4844.227951244857}, {"lowerIsBetter": false, "replicates": [823.1644880446346, 825.5088222507605, 837.2255663511253, 880.4497189256715, 903.7108125399875, 14494.927525362371, 14625.414983400651, 14743.11541307521, 14822.703545929075, 14844.33200505977, 13414.597562073153, 13512.32438378082, 13543.119697219674, 13576.796348054788, 13598.18410895347, 772.355083505271, 779.8674225381699, 919.1406035356963, 929.488781170357, 934.8691183234353, 935.5369092299321, 943.815955888602, 945.2249155692335, 947.4599478297373, 947.9763005924873, 816.824382757707, 988.6342053440237, 993.9701808945744, 998.5905778630763, 1001.1090129784461, 814.5804910471112, 971.1259866120478, 983.2723784399565, 986.1962500562091, 989.4706332112321, 737.4063878577742, 738.2949283434307, 738.3170567225349, 738.6639079218943, 738.6971341749856, 14514.419423223058, 14651.413943442249, 14691.485797997018, 14746.41014359435, 14750.926245368812, 11229.55081796727, 12210.511579536807, 12921.935390322988, 13521.256330901797, 14006.71986560263, 10519.474026298654, 10848.728781780439, 10976.451177441097, 10997.12023038163, 11015.284006539548, 3462.255615042778, 3512.385332566816, 3526.612072672004, 3554.964450355489, 3605.981970090159, 45329.77335113303, 45528.0, 45801.31298030532, 47572.5242747575, 49193.50806491925, 7682.924390585323, 7739.690412383442, 7908.0, 7973.0, 8003.199680032067, 13378.397972091085, 13398.732025359439, 13400.463981440827, 13416.932915335361, 13424.664383390298, 7027.8594428111155, 7046.6828992695455, 7123.287671232939, 7433.331000210007, 7457.925420745832, 4225.57744225575, 4478.104379124188, 4587.449506059294, 4600.240960241523, 4641.234196357631, 2961.1116665000413, 2994.625671791026, 2994.745446637017, 2995.7004299569867, 2996.5055765798834, 460.7166592545627, 467.4180645096876, 474.4188369247644, 477.1316204507832, 477.48193210366446, 546.4863028753039, 557.9386267510571, 563.3071322273627, 565.7510695294058, 660.3429587560312, 7910.683572657029, 7998.560079195584, 8189.71336003249, 8256.793580160544, 8639.654413823502, 562.9549636029108, 575.8761866198704, 647.6308504152682, 656.9408753212139, 671.2482020137477, 3953.2686453006286, 4596.149712303235, 4599.701019433726, 4623.260278355447, 4627.190241707688, 60420.39579604148, 60686.69656651777, 60734.69632651808, 60817.08774368388, 60862.0, 54441.0, 54539.45460545423, 55069.44930550723, 55326.0, 55784.72107639436, 51047.0, 52208.73895630497, 53475.19787203195, 53572.464275357524, 53628.73185634047, 9256.25949924018, 9339.159475647224, 9504.382215155993, 9514.429134251966, 9597.664081757104, 39338.2132357357, 39436.2112757749, 40107.39838902477, 40620.79689601533, 40975.1804963905, 48477.0, 50090.0, 52242.47757522376, 52381.0, 52761.0, 7657.617119144132, 7965.402594805529, 8014.559199244098, 8095.473794203358, 8161.755147345589, 12312.384380780746, 12580.370981451073, 12599.622011339308, 12668.936655316664, 12744.235345878906, 11266.66200014001, 11554.364509952033, 11935.462904169506, 12141.453634586289, 12324.014078873848, 11759.823602646138, 11956.163068585463, 11979.880201198224, 11992.280463172237, 12162.513499459923, 8843.51360675169, 11455.770884582094, 11488.253263537843, 11546.0, 11565.826512602149, 9959.651412200537, 10091.142252908585, 10222.948885255526, 10346.22403319739, 10545.841812372668, 10033.397996120257, 10250.897491024994, 10377.948110259702, 10462.581496740046, 10485.895141048491], "unit": "ms", "name": "yui.html", "value": 6414.847848699331}], "name": "dromaeo_css", "value": 7124.279154416574}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 110.0, 105.0, 111.0, 112.0, 112.0, 102.0, 112.0, 104.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.9}, {"lowerIsBetter": true, "replicates": [157.0, 124.0, 157.0, 164.0, 128.0, 154.0, 148.0, 128.0, 167.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.9}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 161.0, 163.0, 161.0, 159.0, 160.0, 161.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [71.0, 73.0, 72.0, 150.0, 73.0, 71.0, 72.0, 75.0, 163.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 89.2}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 137.0, 94.0, 89.0, 90.0, 132.0, 88.0, 89.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 103.1}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 144.0, 146.0, 146.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 55.0, 56.0, 55.0, 55.0, 55.0, 67.0, 54.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.9}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 64.0, 64.0, 64.0, 63.0, 63.0, 65.0, 63.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.8}, {"lowerIsBetter": true, "replicates": [79.0, 79.0, 80.0, 79.0, 79.0, 78.0, 79.0, 79.0, 78.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 108.0, 106.0, 110.0, 115.0, 109.0, 117.0, 115.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 111.5}, {"lowerIsBetter": true, "replicates": [139.0, 136.0, 136.0, 134.0, 136.0, 132.0, 136.0, 137.0, 146.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.7}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 63.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.9}], "name": "kraken", "value": 1465.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5001665001665], "unit": "score", "name": "Richards", "value": 212.02381199999994}, {"lowerIsBetter": false, "replicates": [170.7067258449983], "unit": "score", "name": "DeltaBlue", "value": 387.31924399999986}, {"lowerIsBetter": false, "replicates": [277.700638711469], "unit": "score", "name": "Encrypt", "value": 958.5177809999998}, {"lowerIsBetter": false, "replicates": [4975.124378109453], "unit": "score", "name": "Decrypt", "value": 53.502381}, {"lowerIsBetter": false, "replicates": [946.969696969697], "unit": "score", "name": "RayTrace", "value": 781.4283840000004}, {"lowerIsBetter": false, "replicates": [563.6978579481398], "unit": "score", "name": "Earley", "value": 1182.3053619999998}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [26179.48717948718], "unit": "score", "name": "RegExp", "value": 34.79766405484819}, {"lowerIsBetter": false, "replicates": [525.5474452554745], "unit": "score", "name": "Splay", "value": 155.05926388888884}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23063.88329431668}]} 14:23:36 INFO - RETURN: dromaeo_css: 7124.28 14:23:36 INFO - RETURN: kraken: 1465.70 14:23:36 INFO - RETURN: v8_7: 23063.88 14:23:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23063.88"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1465.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7124.28"}}} 14:23:36 INFO - Return code: 0 14:23:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:23:36 INFO - # TBPL SUCCESS # 14:23:36 INFO - Running post-action listener: _resource_record_post_action 14:23:36 INFO - Running post-run listener: _resource_record_post_run 14:23:37 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 315392; Write bytes: 495476736; Read time: 76; Write time: 450296 14:23:37 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 234438656; Read time: 0; Write time: 288764 14:23:37 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 315392; Write bytes: 261038080; Read time: 76; Write time: 161532 14:23:37 INFO - Running post-run listener: _upload_blobber_files 14:23:37 INFO - Blob upload gear active. 14:23:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:23:37 INFO - Copying logs to upload dir... 14:23:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1265.953151 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-11 14:23:36.520513) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-11 14:23:36.526123) ========= 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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010086 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452542499/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-11 14:23:36.584156) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:23:36.584519) ========= 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=52faae308a556c440401f8e5000001e8-1452549649.447946-324553832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 14:23:36.634387) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-11 14:23:36.636786) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-11 14:23:36.637193) ========= ========= Total master_lag: 0.23 =========