builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-030 starttime: 1447976688.93 results: success (0) buildid: 20151119143004 builduid: 27b22e4b93924becb9acb0b01385d84b revision: 0c351448cd9f4224bf98709d04e6623b52eef8b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.934947) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.935381) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.935708) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.002896 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.967897) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.968193) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.983269) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:48.983871) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.032640) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.032969) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.033351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.033637) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 15:44:49-- 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% 216M=0s 2015-11-19 15:44:49 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.232451 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.278617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.278952) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.144024 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:49.436339) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 15:44:49.436731) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c351448cd9f4224bf98709d04e6623b52eef8b0 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0c351448cd9f4224bf98709d04e6623b52eef8b0 --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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 15:44:49,487 truncating revision to first 12 chars 2015-11-19 15:44:49,487 Setting DEBUG logging. 2015-11-19 15:44:49,487 attempt 1/10 2015-11-19 15:44:49,487 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c351448cd9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 15:44:50,890 unpacking tar archive at: fx-team-0c351448cd9f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.745288 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 15:44:51.195490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:51.195828) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:51.205763) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:51.206031) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 15:44:51.206440) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 3 secs) (at 2015-11-19 15:44:51.206805) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:44:51 INFO - MultiFileLogger online at 20151119 15:44:51 in /builds/slave/test-pgo 15:44:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:44:51 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:44:51 INFO - {'append_to_log': False, 15:44:51 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:44:51 INFO - 'blob_upload_branch': 'Fx-Team', 15:44:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:44:51 INFO - 'branch': 'Fx-Team', 15:44:51 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:51 INFO - 'config_files': ('talos/linux_config.py',), 15:44:51 INFO - 'default_actions': ('clobber', 15:44:51 INFO - 'read-buildbot-config', 15:44:51 INFO - 'download-and-extract', 15:44:51 INFO - 'populate-webroot', 15:44:51 INFO - 'create-virtualenv', 15:44:51 INFO - 'install', 15:44:51 INFO - 'run-tests'), 15:44:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:51 INFO - 'download_minidump_stackwalk': True, 15:44:51 INFO - 'download_symbols': 'ondemand', 15:44:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:51 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:51 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:51 INFO - 'installer_path': 'installer.exe', 15:44:51 INFO - 'log_level': 'info', 15:44:51 INFO - 'log_name': 'talos', 15:44:51 INFO - 'log_to_console': True, 15:44:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:44:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:44:51 INFO - 'opt_config_files': (), 15:44:51 INFO - 'pip_index': False, 15:44:51 INFO - 'sps_profile': False, 15:44:51 INFO - 'sps_profile_interval': 0, 15:44:51 INFO - 'suite': 'dromaeojs', 15:44:51 INFO - 'system_bits': '64', 15:44:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:51 INFO - 'title': 'talos-linux64-ix-030', 15:44:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:51 INFO - 'use_talos_json': True, 15:44:51 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:44:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:51 INFO - 'work_dir': 'build'} 15:44:51 INFO - ##### 15:44:51 INFO - ##### Running clobber step. 15:44:51 INFO - ##### 15:44:51 INFO - Running pre-action listener: _resource_record_pre_action 15:44:51 INFO - Running main action method: clobber 15:44:51 INFO - rmtree: /builds/slave/test-pgo/build 15:44:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:44:56 INFO - Running post-action listener: _resource_record_post_action 15:44:56 INFO - ##### 15:44:56 INFO - ##### Running read-buildbot-config step. 15:44:56 INFO - ##### 15:44:56 INFO - Running pre-action listener: _resource_record_pre_action 15:44:56 INFO - Running main action method: read_buildbot_config 15:44:56 INFO - Using buildbot properties: 15:44:56 INFO - { 15:44:56 INFO - "properties": { 15:44:56 INFO - "buildnumber": 332, 15:44:56 INFO - "product": "firefox", 15:44:56 INFO - "script_repo_revision": "production", 15:44:56 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:44:56 INFO - "repository": "", 15:44:56 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 15:44:56 INFO - "buildid": "20151119143004", 15:44:56 INFO - "slavename": "talos-linux64-ix-030", 15:44:56 INFO - "pgo_build": "True", 15:44:56 INFO - "basedir": "/builds/slave/test-pgo", 15:44:56 INFO - "project": "", 15:44:56 INFO - "platform": "ubuntu64_hw", 15:44:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:44:56 INFO - "slavebuilddir": "test", 15:44:56 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 15:44:56 INFO - "branch": "fx-team", 15:44:56 INFO - "repo_path": "integration/fx-team", 15:44:56 INFO - "stage_platform": "linux64-pgo", 15:44:56 INFO - "builduid": "27b22e4b93924becb9acb0b01385d84b", 15:44:56 INFO - "revision": "0c351448cd9f4224bf98709d04e6623b52eef8b0" 15:44:56 INFO - }, 15:44:56 INFO - "sourcestamp": { 15:44:56 INFO - "repository": "", 15:44:56 INFO - "hasPatch": false, 15:44:56 INFO - "project": "", 15:44:56 INFO - "branch": "fx-team-linux64-pgo-talos", 15:44:56 INFO - "changes": [ 15:44:56 INFO - { 15:44:56 INFO - "category": null, 15:44:56 INFO - "files": [ 15:44:56 INFO - { 15:44:56 INFO - "url": null, 15:44:56 INFO - "name": "https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 15:44:56 INFO - } 15:44:56 INFO - ], 15:44:56 INFO - "repository": "", 15:44:56 INFO - "rev": "0c351448cd9f4224bf98709d04e6623b52eef8b0", 15:44:56 INFO - "who": "sendchange", 15:44:56 INFO - "when": 1447976319, 15:44:56 INFO - "number": 6685828, 15:44:56 INFO - "comments": "", 15:44:56 INFO - "project": "", 15:44:56 INFO - "at": "Thu 19 Nov 2015 15:38:39", 15:44:56 INFO - "branch": "fx-team-linux64-pgo-talos", 15:44:56 INFO - "revlink": "", 15:44:56 INFO - "properties": [ 15:44:56 INFO - [ 15:44:56 INFO - "buildid", 15:44:56 INFO - "20151119143004", 15:44:56 INFO - "Change" 15:44:56 INFO - ], 15:44:56 INFO - [ 15:44:56 INFO - "builduid", 15:44:56 INFO - "27b22e4b93924becb9acb0b01385d84b", 15:44:56 INFO - "Change" 15:44:56 INFO - ], 15:44:56 INFO - [ 15:44:56 INFO - "pgo_build", 15:44:56 INFO - "True", 15:44:56 INFO - "Change" 15:44:56 INFO - ] 15:44:56 INFO - ], 15:44:56 INFO - "revision": "0c351448cd9f4224bf98709d04e6623b52eef8b0" 15:44:56 INFO - } 15:44:56 INFO - ], 15:44:56 INFO - "revision": "0c351448cd9f4224bf98709d04e6623b52eef8b0" 15:44:56 INFO - } 15:44:56 INFO - } 15:44:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 15:44:56 INFO - Running post-action listener: _resource_record_post_action 15:44:56 INFO - ##### 15:44:56 INFO - ##### Running download-and-extract step. 15:44:56 INFO - ##### 15:44:56 INFO - Running pre-action listener: _resource_record_pre_action 15:44:56 INFO - Running main action method: download_and_extract 15:44:56 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:44:56 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')]} 15:44:56 INFO - https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:44:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/test_packages.json 15:44:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/test_packages.json 15:44:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:44:56 INFO - Downloaded 1302 bytes. 15:44:56 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:44:56 INFO - Using the following test package requirements: 15:44:56 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:44:56 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:44:56 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'jsshell-linux-x86_64.zip'], 15:44:56 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:44:56 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:44:56 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:44:56 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:44:56 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:44:56 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:44:56 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:44:56 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:44:56 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')]} 15:44:56 INFO - https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:44:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:44:57 INFO - Downloaded 22523464 bytes. 15:44:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:44:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:44:59 INFO - Return code: 0 15:44:59 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:44:59 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')]} 15:44:59 INFO - https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:44:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:00 INFO - Downloaded 22523464 bytes. 15:45:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:45:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:45:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:45:01 INFO - Return code: 0 15:45: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')]} 15:45:01 INFO - https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:45:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:45:02 INFO - Downloaded 11130997 bytes. 15:45:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 15:45:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:03 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 15:45:03 INFO - Return code: 0 15:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:03 INFO - https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:45:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:45:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:45:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:45:05 INFO - Downloaded 57347216 bytes. 15:45:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:45:05 INFO - mkdir: /builds/slave/test-pgo/properties 15:45:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:45:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:45:05 INFO - Contents: 15:45:05 INFO - build_url:https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:45:05 INFO - Running post-action listener: _resource_record_post_action 15:45:05 INFO - Running post-action listener: set_extra_try_arguments 15:45:05 INFO - ##### 15:45:05 INFO - ##### Running populate-webroot step. 15:45:05 INFO - ##### 15:45:05 INFO - Running pre-action listener: _resource_record_pre_action 15:45:05 INFO - Running main action method: populate_webroot 15:45:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:45:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:45:05 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:45:05 INFO - '../fennec_ids.txt'], 15:45:05 INFO - 'tests': ['tcheck2']}, 15:45:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:45:05 INFO - '--tppagecycles', 15:45:05 INFO - '7'], 15:45:05 INFO - 'tests': ['tsvgm']}}, 15:45:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:45:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:45:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:45:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:45:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'tests': ['damp', 'tps']}, 15:45:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['damp', 'tps']}, 15:45:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:45:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:45:05 INFO - 'other': {'tests': ['a11yr', 15:45:05 INFO - 'ts_paint', 15:45:05 INFO - 'tpaint', 15:45:05 INFO - 'sessionrestore', 15:45:05 INFO - 'sessionrestore_no_auto_restore']}, 15:45:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['a11yr', 15:45:05 INFO - 'ts_paint', 15:45:05 INFO - 'tpaint', 15:45:05 INFO - 'sessionrestore', 15:45:05 INFO - 'sessionrestore_no_auto_restore']}, 15:45:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['a11yr', 15:45:05 INFO - 'ts_paint', 15:45:05 INFO - 'tpaint', 15:45:05 INFO - 'sessionrestore', 15:45:05 INFO - 'sessionrestore_no_auto_restore']}, 15:45:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['a11yr', 15:45:05 INFO - 'ts_paint', 15:45:05 INFO - 'tpaint', 15:45:05 INFO - 'sessionrestore', 15:45:05 INFO - 'sessionrestore_no_auto_restore']}, 15:45:05 INFO - 'other_l64': {'tests': ['a11yr', 15:45:05 INFO - 'ts_paint', 15:45:05 INFO - 'tpaint', 15:45:05 INFO - 'sessionrestore', 15:45:05 INFO - 'sessionrestore_no_auto_restore']}, 15:45:05 INFO - 'other_nol64': {'tests': ['a11yr', 15:45:05 INFO - 'ts_paint', 15:45:05 INFO - 'tpaint', 15:45:05 INFO - 'sessionrestore', 15:45:05 INFO - 'sessionrestore_no_auto_restore']}, 15:45:05 INFO - 'svgr': {'tests': ['tsvgx', 15:45:05 INFO - 'tsvgr_opacity', 15:45:05 INFO - 'tart', 15:45:05 INFO - 'tscrollx', 15:45:05 INFO - 'cart']}, 15:45:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['tsvgx', 15:45:05 INFO - 'tsvgr_opacity', 15:45:05 INFO - 'tart', 15:45:05 INFO - 'tscrollx', 15:45:05 INFO - 'cart']}, 15:45:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'tests': ['tp5o']}, 15:45:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'talos_options': ['--e10s'], 15:45:05 INFO - 'tests': ['tp5o']}, 15:45:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'talos_options': ['--xperf_path', 15:45:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:05 INFO - 'tests': ['tp5n']}, 15:45:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:05 INFO - 'talos_options': ['--e10s', 15:45:05 INFO - '--xperf_path', 15:45:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:05 INFO - 'tests': ['tp5n']}}, 15:45:05 INFO - 'talos.zip': {'path': '', 15:45:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:45:05 INFO - Running post-action listener: _resource_record_post_action 15:45:05 INFO - ##### 15:45:05 INFO - ##### Running create-virtualenv step. 15:45:05 INFO - ##### 15:45:05 INFO - Running pre-action listener: _resource_record_pre_action 15:45:05 INFO - Running main action method: create_virtualenv 15:45:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:45:05 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 15:45:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:45:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:45:05 INFO - Using real prefix '/usr' 15:45:05 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:45:06 INFO - Installing distribute.............................................................................................................................................................................................done. 15:45:09 INFO - Installing pip.................done. 15:45:09 INFO - Return code: 0 15:45:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:09 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 15:45:09 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 15:45:09 INFO - Using env: {'DISPLAY': ':0', 15:45:09 INFO - 'HOME': '/home/cltbld', 15:45:09 INFO - 'LANG': 'en_US.UTF-8', 15:45:09 INFO - 'LANGUAGE': 'en_US:en', 15:45:09 INFO - 'LOGNAME': 'cltbld', 15:45:09 INFO - 'MAIL': '/var/mail/cltbld', 15:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:09 INFO - 'MOZ_NO_REMOTE': '1', 15:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:09 INFO - 'NO_EM_RESTART': '1', 15:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:09 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:09 INFO - 'SHELL': '/bin/bash', 15:45:09 INFO - 'SHLVL': '1', 15:45:09 INFO - 'TERM': 'linux', 15:45:09 INFO - 'TMOUT': '86400', 15:45:09 INFO - 'USER': 'cltbld', 15:45:09 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:09 INFO - '_': '/tools/buildbot/bin/python'} 15:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:09 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 15:45:09 INFO - Cleaning up... 15:45:09 INFO - Return code: 0 15:45:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:09 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 15:45:09 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 15:45:09 INFO - Using env: {'DISPLAY': ':0', 15:45:09 INFO - 'HOME': '/home/cltbld', 15:45:09 INFO - 'LANG': 'en_US.UTF-8', 15:45:09 INFO - 'LANGUAGE': 'en_US:en', 15:45:09 INFO - 'LOGNAME': 'cltbld', 15:45:09 INFO - 'MAIL': '/var/mail/cltbld', 15:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:09 INFO - 'MOZ_NO_REMOTE': '1', 15:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:09 INFO - 'NO_EM_RESTART': '1', 15:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:09 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:09 INFO - 'SHELL': '/bin/bash', 15:45:09 INFO - 'SHLVL': '1', 15:45:09 INFO - 'TERM': 'linux', 15:45:09 INFO - 'TMOUT': '86400', 15:45:09 INFO - 'USER': 'cltbld', 15:45:09 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:09 INFO - '_': '/tools/buildbot/bin/python'} 15:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:10 INFO - Downloading/unpacking psutil>=0.7.1 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45:11 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:45:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:45:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:45:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:11 INFO - Installing collected packages: psutil 15:45:11 INFO - Running setup.py install for psutil 15:45:11 INFO - building 'psutil._psutil_linux' extension 15:45:11 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 15:45:13 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 15:45:13 INFO - building 'psutil._psutil_posix' extension 15:45:13 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 15:45:13 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 15:45:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:13 INFO - Successfully installed psutil 15:45:13 INFO - Cleaning up... 15:45:13 INFO - Return code: 0 15:45:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:13 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 15:45:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:45:13 INFO - Using env: {'DISPLAY': ':0', 15:45:13 INFO - 'HOME': '/home/cltbld', 15:45:13 INFO - 'LANG': 'en_US.UTF-8', 15:45:13 INFO - 'LANGUAGE': 'en_US:en', 15:45:13 INFO - 'LOGNAME': 'cltbld', 15:45:13 INFO - 'MAIL': '/var/mail/cltbld', 15:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:13 INFO - 'MOZ_NO_REMOTE': '1', 15:45:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:13 INFO - 'NO_EM_RESTART': '1', 15:45:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:13 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:13 INFO - 'SHELL': '/bin/bash', 15:45:13 INFO - 'SHLVL': '1', 15:45:13 INFO - 'TERM': 'linux', 15:45:13 INFO - 'TMOUT': '86400', 15:45:13 INFO - 'USER': 'cltbld', 15:45:13 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:13 INFO - '_': '/tools/buildbot/bin/python'} 15:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:45:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:45:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:45:15 INFO - Installing collected packages: mozsystemmonitor 15:45:15 INFO - Running setup.py install for mozsystemmonitor 15:45:15 INFO - Successfully installed mozsystemmonitor 15:45:15 INFO - Cleaning up... 15:45:15 INFO - Return code: 0 15:45:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:15 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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:15 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 15:45:15 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 15:45:15 INFO - Using env: {'DISPLAY': ':0', 15:45:15 INFO - 'HOME': '/home/cltbld', 15:45:15 INFO - 'LANG': 'en_US.UTF-8', 15:45:15 INFO - 'LANGUAGE': 'en_US:en', 15:45:15 INFO - 'LOGNAME': 'cltbld', 15:45:15 INFO - 'MAIL': '/var/mail/cltbld', 15:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:15 INFO - 'MOZ_NO_REMOTE': '1', 15:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:15 INFO - 'NO_EM_RESTART': '1', 15:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:15 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:15 INFO - 'SHELL': '/bin/bash', 15:45:15 INFO - 'SHLVL': '1', 15:45:15 INFO - 'TERM': 'linux', 15:45:15 INFO - 'TMOUT': '86400', 15:45:15 INFO - 'USER': 'cltbld', 15:45:15 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:15 INFO - '_': '/tools/buildbot/bin/python'} 15:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:15 INFO - Downloading/unpacking blobuploader==1.2.4 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:17 INFO - Downloading blobuploader-1.2.4.tar.gz 15:45:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:45:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:45:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:45:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:45:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:18 INFO - Downloading docopt-0.6.1.tar.gz 15:45:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:45:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:45:18 INFO - Installing collected packages: blobuploader, requests, docopt 15:45:18 INFO - Running setup.py install for blobuploader 15:45:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:45:18 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:45:18 INFO - Running setup.py install for requests 15:45:18 INFO - Running setup.py install for docopt 15:45:18 INFO - Successfully installed blobuploader requests docopt 15:45:18 INFO - Cleaning up... 15:45:18 INFO - Return code: 0 15:45:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:45:18 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')]} 15:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:18 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')]} 15:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:18 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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:18 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 15:45:18 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 15:45:18 INFO - Using env: {'DISPLAY': ':0', 15:45:18 INFO - 'HOME': '/home/cltbld', 15:45:18 INFO - 'LANG': 'en_US.UTF-8', 15:45:18 INFO - 'LANGUAGE': 'en_US:en', 15:45:18 INFO - 'LOGNAME': 'cltbld', 15:45:18 INFO - 'MAIL': '/var/mail/cltbld', 15:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:18 INFO - 'MOZ_NO_REMOTE': '1', 15:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:18 INFO - 'NO_EM_RESTART': '1', 15:45:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:18 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:18 INFO - 'SHELL': '/bin/bash', 15:45:18 INFO - 'SHLVL': '1', 15:45:18 INFO - 'TERM': 'linux', 15:45:18 INFO - 'TMOUT': '86400', 15:45:18 INFO - 'USER': 'cltbld', 15:45:18 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:18 INFO - '_': '/tools/buildbot/bin/python'} 15:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:45:19 INFO - Running setup.py (path:/tmp/pip-O4FCFH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:45:19 INFO - Running setup.py (path:/tmp/pip-laOFiV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:45:19 INFO - Running setup.py (path:/tmp/pip-aC1ipa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:45:19 INFO - Running setup.py (path:/tmp/pip-HakcKP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:45:19 INFO - Running setup.py (path:/tmp/pip-mNQN7E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:45:19 INFO - Running setup.py (path:/tmp/pip-liEppq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:45:19 INFO - Running setup.py (path:/tmp/pip-zHlbxL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:45:19 INFO - Running setup.py (path:/tmp/pip-aNLjw1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:45:19 INFO - Running setup.py (path:/tmp/pip-OsMzxj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:45:19 INFO - Running setup.py (path:/tmp/pip-2k_zDz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:45:19 INFO - Running setup.py (path:/tmp/pip-gd8rsC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:45:19 INFO - Running setup.py (path:/tmp/pip-EAWqHw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:45:19 INFO - Running setup.py (path:/tmp/pip-8dbU2P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:45:19 INFO - Running setup.py (path:/tmp/pip-_e0quw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:45:20 INFO - Running setup.py (path:/tmp/pip-io0Yex-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:45:20 INFO - Running setup.py (path:/tmp/pip-4dvEsU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:45:20 INFO - Running setup.py (path:/tmp/pip-oatSaS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:45:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:45:20 INFO - Running setup.py install for manifestparser 15:45:20 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:45:20 INFO - Running setup.py install for mozcrash 15:45:20 INFO - Running setup.py install for mozdebug 15:45:20 INFO - Running setup.py install for mozdevice 15:45:20 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:45:20 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:45:20 INFO - Running setup.py install for mozfile 15:45:20 INFO - Running setup.py install for mozhttpd 15:45:20 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:45:20 INFO - Running setup.py install for mozinfo 15:45:20 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:45:20 INFO - Running setup.py install for mozInstall 15:45:21 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Running setup.py install for mozleak 15:45:21 INFO - Running setup.py install for mozlog 15:45:21 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Running setup.py install for moznetwork 15:45:21 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Running setup.py install for mozprocess 15:45:21 INFO - Running setup.py install for mozprofile 15:45:21 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Running setup.py install for mozrunner 15:45:21 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Running setup.py install for mozscreenshot 15:45:21 INFO - Running setup.py install for moztest 15:45:21 INFO - Running setup.py install for mozversion 15:45:21 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:45:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:45:21 INFO - Cleaning up... 15:45:22 INFO - Return code: 0 15:45:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:45:22 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')]} 15:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:22 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')]} 15:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:22 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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:22 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 15:45:22 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 15:45:22 INFO - Using env: {'DISPLAY': ':0', 15:45:22 INFO - 'HOME': '/home/cltbld', 15:45:22 INFO - 'LANG': 'en_US.UTF-8', 15:45:22 INFO - 'LANGUAGE': 'en_US:en', 15:45:22 INFO - 'LOGNAME': 'cltbld', 15:45:22 INFO - 'MAIL': '/var/mail/cltbld', 15:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:22 INFO - 'MOZ_NO_REMOTE': '1', 15:45:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:22 INFO - 'NO_EM_RESTART': '1', 15:45:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:22 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:22 INFO - 'SHELL': '/bin/bash', 15:45:22 INFO - 'SHLVL': '1', 15:45:22 INFO - 'TERM': 'linux', 15:45:22 INFO - 'TMOUT': '86400', 15:45:22 INFO - 'USER': 'cltbld', 15:45:22 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:22 INFO - '_': '/tools/buildbot/bin/python'} 15:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:45:22 INFO - Running setup.py (path:/tmp/pip-X4KsQk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:45:22 INFO - Running setup.py (path:/tmp/pip-z6Lygy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:45:22 INFO - Running setup.py (path:/tmp/pip-_B2VCX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:45:22 INFO - Running setup.py (path:/tmp/pip-4y8CBw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:45:22 INFO - Running setup.py (path:/tmp/pip-_X5qn6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:45:22 INFO - Running setup.py (path:/tmp/pip-hmsaIB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:45:22 INFO - Running setup.py (path:/tmp/pip-hZtg7p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:45:22 INFO - Running setup.py (path:/tmp/pip-kBTksH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:45:22 INFO - Running setup.py (path:/tmp/pip-EGJQkB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:45:22 INFO - Running setup.py (path:/tmp/pip-nMHyba-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:45:22 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)) 15:45:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:45:22 INFO - Running setup.py (path:/tmp/pip-jQR0vh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:45:23 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)) 15:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:45:23 INFO - Running setup.py (path:/tmp/pip-8hp0h3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:45:23 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)) 15:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:45:23 INFO - Running setup.py (path:/tmp/pip-vyk6iL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:45:23 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)) 15:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:45:23 INFO - Running setup.py (path:/tmp/pip-WFFYjM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:45:23 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)) 15:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:45:23 INFO - Running setup.py (path:/tmp/pip-AxePkP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:45:23 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)) 15:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:45:23 INFO - Running setup.py (path:/tmp/pip-Kh62OV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:45:23 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)) 15:45:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:45:23 INFO - Running setup.py (path:/tmp/pip-89XdCt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:45:23 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)) 15:45:23 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)) 15:45:23 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)) 15:45:23 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)) 15:45:23 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:45:23 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:45:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:23 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 15:45:23 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 15:45:23 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 15:45:23 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 15:45:23 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 15:45:23 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 15:45:25 INFO - Downloading blessings-1.5.1.tar.gz 15:45:25 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 15:45:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:25 INFO - Installing collected packages: blessings 15:45:25 INFO - Running setup.py install for blessings 15:45:25 INFO - Successfully installed blessings 15:45:25 INFO - Cleaning up... 15:45:25 INFO - Return code: 0 15:45:25 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:45:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:45:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:45:25 INFO - Reading from file tmpfile_stdout 15:45:25 INFO - Current package versions: 15:45:25 INFO - argparse == 1.2.1 15:45:25 INFO - blessings == 1.5.1 15:45:25 INFO - blobuploader == 1.2.4 15:45:25 INFO - docopt == 0.6.1 15:45:25 INFO - manifestparser == 1.1 15:45:25 INFO - mozInstall == 1.12 15:45:25 INFO - mozcrash == 0.16 15:45:25 INFO - mozdebug == 0.1 15:45:25 INFO - mozdevice == 0.47 15:45:25 INFO - mozfile == 1.2 15:45:25 INFO - mozhttpd == 0.7 15:45:25 INFO - mozinfo == 0.9 15:45:25 INFO - mozleak == 0.1 15:45:25 INFO - mozlog == 3.0 15:45:25 INFO - moznetwork == 0.27 15:45:25 INFO - mozprocess == 0.22 15:45:25 INFO - mozprofile == 0.27 15:45:25 INFO - mozrunner == 6.11 15:45:25 INFO - mozscreenshot == 0.1 15:45:25 INFO - mozsystemmonitor == 0.0 15:45:25 INFO - moztest == 0.7 15:45:25 INFO - mozversion == 1.4 15:45:25 INFO - psutil == 3.1.1 15:45:25 INFO - requests == 1.2.3 15:45:25 INFO - wsgiref == 0.1.2 15:45:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:45:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:25 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 0x7f41b5162c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f596c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', '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 15:45:25 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 15:45:25 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 15:45:25 INFO - Using env: {'DISPLAY': ':0', 15:45:25 INFO - 'HOME': '/home/cltbld', 15:45:25 INFO - 'LANG': 'en_US.UTF-8', 15:45:25 INFO - 'LANGUAGE': 'en_US:en', 15:45:25 INFO - 'LOGNAME': 'cltbld', 15:45:25 INFO - 'MAIL': '/var/mail/cltbld', 15:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:25 INFO - 'MOZ_NO_REMOTE': '1', 15:45:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:25 INFO - 'NO_EM_RESTART': '1', 15:45:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:25 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:25 INFO - 'SHELL': '/bin/bash', 15:45:25 INFO - 'SHLVL': '1', 15:45:25 INFO - 'TERM': 'linux', 15:45:25 INFO - 'TMOUT': '86400', 15:45:25 INFO - 'USER': 'cltbld', 15:45:25 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:25 INFO - '_': '/tools/buildbot/bin/python'} 15:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 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)) 15:45:25 INFO - Cleaning up... 15:45:25 INFO - Return code: 0 15:45:25 INFO - Running post-action listener: _resource_record_post_action 15:45:25 INFO - Running post-action listener: _start_resource_monitoring 15:45:25 INFO - Starting resource monitoring. 15:45:25 INFO - ##### 15:45:25 INFO - ##### Running install step. 15:45:25 INFO - ##### 15:45:25 INFO - Running pre-action listener: _resource_record_pre_action 15:45:25 INFO - Running main action method: install 15:45:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:45:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:45:26 INFO - Reading from file tmpfile_stdout 15:45:26 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:45:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:45:26 INFO - Reading from file tmpfile_stdout 15:45:26 INFO - Output received: 15:45:26 INFO - Usage: mozinstall [options] installer 15:45:26 INFO - Options: 15:45:26 INFO - -h, --help show this help message and exit 15:45:26 INFO - -d DEST, --destination=DEST 15:45:26 INFO - Directory to install application into. [default: 15:45:26 INFO - "/builds/slave/test-pgo"] 15:45:26 INFO - --app=APP Application being installed. [default: firefox] 15:45:26 INFO - mkdir: /builds/slave/test-pgo/build/application 15:45:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 15:45:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 15:45:40 INFO - Reading from file tmpfile_stdout 15:45:40 INFO - Output received: 15:45:40 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:45:40 INFO - Running post-action listener: _resource_record_post_action 15:45:40 INFO - ##### 15:45:40 INFO - ##### Running run-tests step. 15:45:40 INFO - ##### 15:45:40 INFO - Running pre-action listener: _resource_record_pre_action 15:45:40 INFO - Running main action method: run_tests 15:45:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:45:40 INFO - Python 2.7.3 15:45:40 INFO - Return code: 0 15:45:40 INFO - grabbing minidump binary from tooltool 15:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:40 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 0x204fd70>, '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 0x2050b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2050fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:45:40 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 15:45:40 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 15:45:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:40 INFO - Return code: 0 15:45:40 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:45:40 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:45:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:45:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:45:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:45:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost 15:45:40 INFO - Using env: {'DISPLAY': ':0', 15:45:40 INFO - 'HOME': '/home/cltbld', 15:45:40 INFO - 'LANG': 'en_US.UTF-8', 15:45:40 INFO - 'LANGUAGE': 'en_US:en', 15:45:40 INFO - 'LOGNAME': 'cltbld', 15:45:40 INFO - 'MAIL': '/var/mail/cltbld', 15:45:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:45:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:40 INFO - 'MOZ_NO_REMOTE': '1', 15:45:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:45:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:40 INFO - 'NO_EM_RESTART': '1', 15:45:40 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:45:40 INFO - 'PWD': '/builds/slave/test-pgo', 15:45:40 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:45:40 INFO - 'SHELL': '/bin/bash', 15:45:40 INFO - 'SHLVL': '1', 15:45:40 INFO - 'TERM': 'linux', 15:45:40 INFO - 'TMOUT': '86400', 15:45:40 INFO - 'USER': 'cltbld', 15:45:40 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142', 15:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:40 INFO - '_': '/tools/buildbot/bin/python'} 15:45:40 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] with output_timeout 3600 15:45:40 INFO - mozversion INFO | application_buildid: 20151119143004 15:45:40 INFO - mozversion INFO | application_changeset: 0c351448cd9f4224bf98709d04e6623b52eef8b0 15:45:40 INFO - mozversion INFO | application_display_name: Nightly 15:45:40 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:40 INFO - mozversion INFO | application_name: Firefox 15:45:40 INFO - mozversion INFO | application_remotingname: firefox 15:45:40 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 15:45:40 INFO - mozversion INFO | application_vendor: Mozilla 15:45:40 INFO - mozversion INFO | application_version: 45.0a1 15:45:40 INFO - mozversion INFO | platform_buildid: 20151119143004 15:45:40 INFO - mozversion INFO | platform_changeset: 0c351448cd9f4224bf98709d04e6623b52eef8b0 15:45:40 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 15:45:40 INFO - mozversion INFO | platform_version: 45.0a1 15:45:40 INFO - 2015-11-19 15:45:40,920 DEBUG : using testdate: 1447976740 15:45:40 INFO - 2015-11-19 15:45:40,920 DEBUG : actual date: 1447976740 15:45:40 INFO - 2015-11-19 15:45:40,928 INFO : starting webserver on 'localhost:40158' 15:45:40 INFO - 2015-11-19 15:45:40,928 INFO : Starting test suite talos-linux64-ix-030 15:45:40 INFO - 2015-11-19 15:45:40,929 INFO : Starting test dromaeo_css 15:45:40 INFO - 2015-11-19 15:45:40,929 DEBUG : operating with platform_type : linux_ 15:45:40 INFO - 2015-11-19 15:45:40,929 INFO : Initialising browser for dromaeo_css test... 15:45:40 INFO - 2015-11-19 15:45:40,946 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0G9uc8/profile http://localhost:40158/getInfo.html 15:45:45 INFO - 2015-11-19 15:45:45,934 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:45:45 INFO - 2015-11-19 15:45:45,934 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:45:45 INFO - 2015-11-19 15:45:45,934 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:45:45 INFO - 2015-11-19 15:45:45,934 DEBUG : BROWSER_OUTPUT: __metrics 15:45:45 INFO - 2015-11-19 15:45:45,998 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:45:47 INFO - 2015-11-19 15:45:47,265 INFO : Browser initialized. 15:45:47 INFO - 2015-11-19 15:45:47,265 INFO : Running cycle 1/1 for dromaeo_css test... 15:45:47 INFO - 2015-11-19 15:45:47,266 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0G9uc8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:45:59 INFO - 2015-11-19 15:45:59,001 DEBUG : BROWSER_OUTPUT: RSS: Main: 142229504 15:45:59 INFO - 2015-11-19 15:45:59,001 DEBUG : BROWSER_OUTPUT: 15:46:50 INFO - 2015-11-19 15:46:50,690 DEBUG : BROWSER_OUTPUT: 1447976810685 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] 15:48:59 INFO - 2015-11-19 15:48:59,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40158/tests/dromaeo/cssquery-ext.html) 15:49:00 INFO - 2015-11-19 15:49:00,788 DEBUG : BROWSER_OUTPUT: RSS: Main: 268767232 15:49:00 INFO - 2015-11-19 15:49:00,788 DEBUG : BROWSER_OUTPUT: 15:52:03 INFO - 2015-11-19 15:52:03,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/dromaeo/cssquery-ext.html (next: http://localhost:40158/tests/dromaeo/cssquery-jquery.html) 15:52:03 INFO - 2015-11-19 15:52:03,672 DEBUG : BROWSER_OUTPUT: RSS: Main: 221577216 15:52:03 INFO - 2015-11-19 15:52:03,672 DEBUG : BROWSER_OUTPUT: 15:55:05 INFO - 2015-11-19 15:55:05,748 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40158/tests/dromaeo/cssquery-mootools.html) 15:55:06 INFO - 2015-11-19 15:55:06,312 DEBUG : BROWSER_OUTPUT: RSS: Main: 215629824 15:55:06 INFO - 2015-11-19 15:55:06,313 DEBUG : BROWSER_OUTPUT: 15:58:08 INFO - 2015-11-19 15:58:08,179 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40158/tests/dromaeo/cssquery-prototype.html) 15:58:08 INFO - 2015-11-19 15:58:08,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 199426048 15:58:08 INFO - 2015-11-19 15:58:08,769 DEBUG : BROWSER_OUTPUT: 16:01:11 INFO - 2015-11-19 16:01:11,309 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40158/tests/dromaeo/cssquery-yui.html) 16:01:11 INFO - 2015-11-19 16:01:11,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 204582912 16:01:11 INFO - 2015-11-19 16:01:11,890 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,687 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/dromaeo/cssquery-yui.html (next: http://localhost:40158/tests/dromaeo/cssquery-dojo.html) 16:04:14 INFO - 2015-11-19 16:04:14,945 DEBUG : BROWSER_OUTPUT: RSS: Main: 252243968 16:04:14 INFO - 2015-11-19 16:04:14,946 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,946 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:04:14 INFO - 2015-11-19 16:04:14,946 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:04:14 INFO - 2015-11-19 16:04:14,946 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:04:14 INFO - 2015-11-19 16:04:14,947 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:04:14 INFO - 2015-11-19 16:04:14,947 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3176.523521471779;3219.1952011997;3259.4621887388566;3261.0542942546663;3272.214668479565;5197.766100525476;5302;5386.192071189319;5422.972885135574;5460.808871689496;5440.102383106782;5469.042917489443;5520.530754885839;5527.585431092664;5530.778768849241;4770.585997519849;4973.6621305623385;4998.125328067583;5005.124103281929;5121;5054.721990290533;5069.974650126745;5117.181250999841;5123;5134.768935397916;4883.633727470418;4901.607871370282;4992.850214493535;5008.424031236418;5022.6233032522705;4966.652334336598;4991.750412479361;5001.474845141244;5003.924941125886;5051.090803655341;4951.232558953332;4957.727324997124;4980.377452818398;5009.373828271466;5032.471590482984;5105.33630628016;5144.228365745131;5147.948520514784;5178.171492561172;5233.5551478124025;5881.382454842223;5890.646561206341;5895.675737834417;5897.233359663259;5902.291724993029;5739.196512488255;5931.169636250928;5935;5941.19793827832;5946.672932988684;5410.053240682904;5410.56715462766;5411.296531450926;5443.2107344434835;5494.697791621419;5977.551683623746;6023.969880150659;6038.758449662052;6061.424164704346;6113.57204995655;5549.306336707911;5851.268591426072;5855.3266374367495;5860.296764388216;5900.43945825146;5787.016207244695;5845.240118784531;5860.208871802252;5894.056950887837;5984.162217289583;5834.124881267759;5847;5862.941370586324;5864.296284445893;5876.294844618587;5730.570207234474;5876.559258055663;5883.470487656132;5883.617564858356;5886.381929897385;4308.806103725277;4325.351197320427;4371.540988196257;4405.7797110145;4407.030453300269;3911.4523966644692;3918.9804050979565;3924.2151569685607;3931.4692516509904;3962.881113566598;5480.095784195642;5581.4976652100695;5598.44015598445;5599.412061733541;5613.073842815971;5793.826185214451;5800.88398232033;5815.709214539256;5831.7667293307795;5861.531077513789;5551.278333816578;5619.129034999582;5623.606347555632;5624.184493248456;5630.268065151504;5009.24861270805;5143.7685304161405;5166.767495462713;5168.9483105168465;5212.530872221519;6020.488258497989;6088.4215999479975;6092;6124.418180272867;6136.5090792737365;5778.219940308173;5810.68041257718;5837.562182836389;5842.474177323891;5845.561582881386;5905.52755779528;6037.18498002764;6063.484603808725;6065.2418447694035;6071.817845464643;5658.915116273177;5666.886662266649;5735.684537350486;5741;5798.942010580009;5844.532437405082;5874.647521148745;5878.941210587839;5887.087501437284;5892.086726557391;5480.698561579152;5782.942170578241;5794.1308803679785;5806.68063256525;5807.245058142415;5616.21373007771;5644.717764111861;5652.0674088774895;5671.858203544944;5732.57005724564;5607.327120745536;5636.408177141505;5650.604457687922;5656.123300888369;5664.915026274692;5640.746166422603;5713.114467257582;5747.942520574741;5752.9137062943255;5777.6822274775295;5587.0502014656795;5602.299712535933;5657.688827114548;5692.743826527733;5736.799212027559 16:04:14 INFO - 2015-11-19 16:04:14,947 DEBUG : BROWSER_OUTPUT: |1;ext.html;556.866776110615;591.6213623281101;598.2521847690384;599.8920194365015;601.5007543738698;31534.526982095285;39176;39559;39568.40647390291;39702.99999999996;41884.790576047155;42222;42404.151916961644;42427;42520.78739606297;7681.731139410122;7795.883061754078;8780.25367843734;8986.415882967618;10254.487275636226;10421.478926053705;10530.947345263263;10539.947300263488;10543.525541350618;10552.78894422111;10498.107660848837;10609.098226650744;10617.0975467085;10619.46902654868;10621.256512044121;7755.534667919943;7937.166597507266;10599.0990765785;10875.347479151196;10888.67333979974;10341.431221282826;10524;10530.104941080017;10530.684079477627;10532.15742740579;39112;39573.20853582941;39574;39638;39673.404898926536;3218.211538173139;3321.634620191777;3493.2314890724;3999.8600048998437;4083.224187404384;29676;29864.55203171954;30033.249168770955;30066.699333006607;30607.234819129477;3894.2795582816984;3924.548676902136;3985.20295940813;3987.9601203987877;4019.3970904364287;261995;262142;262600;262628;264904;54210.72894635502;54892.17661735077;55034.44965550373;55361;55931.72034139803;52658;52759;53069;53201.46798531965;53211.201831972554;14965.92517037408;15080.321385537678;15097.698046039017;15165.24173791328;15167.772483412758;11778.175527713149;11833;11833.230839995373;11839.940800295943;11841.230320029343;17987.550311242325;18463.90768046151;18692.813071869106;18701.438956831316;18754.156062976926;7666.923330766733;7780.961095194488;7892.368610511146;7929.841403171904;8007.519548827089;958.7411398922252;977.7653363192925;985.8373368394278;1014.0619926567895;1053.7418332508585;28094.893606405985;28310.292242694097;28740.281492962844;28878.855605721837;28884.566731499046;1059.9841002384971;1080.6541906589973;1148.4315263944402;1194.2476239968764;1204.5030186866209;14898.925505372405;14968.326425310886;14980.775288370683;15159.696806064037;15163.241837908061;299916;302912;303514;303945;303980;51549.48450515447;52328.738356308735;52448;52462.47537524576;52463.47536524586;1308.2281453942219;1327.8804907558367;1331.3609467455483;1333.9799903001463;1345;27434.5884811724;28292.434151316447;28374.85812570924;28415.431691365644;28627.14118576446;45631;46204.768976154905;46235.76882115568;46295.76852115853;46303.76848115738;283735;284035;285027;285283;285402;27893.581596275668;30797.8460107698;31338.21654458657;31370;31865.203369915936;38083.428748568236;38176.23647526978;38718.225635487695;39687.60312396839;39703.40444893387;33117.83441082779;34479.65520344764;35010.474842876865;35350.64649353473;35726;31121.37757244797;34862.65137348594;36331.45502817513;36419.817900910326;39515.209695805344;35327.470087949216;35664.64335356717;36053.81973090118;36152.096197595274;36185.45721814122;30587.388252235276;32062.35875282528;32269;32716.83641581872;32771.50842737313;24552.38619034467;24750.504989900463;24750.628740568547;25042.248732538053;25205.369865753502 16:04:14 INFO - 2015-11-19 16:04:14,947 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.6057296114833;354.12001177074967;354.12884304610657;355.9911002224944;359.18106716685946;13713.3828977696;14122.576322710334;15940.521784346474;16485.258163382645;17601.82398176018;16389.91805040976;16551.172441377945;17012.744808827876;17412.651746965086;17425.738613920803;5244.292020577212;5450.781968721247;5454.890902181954;5455.072637651589;5458.727063646822;3913.941290880639;4049.1496785675045;5384.380796208447;5931.110333449974;6103.572749907508;5996.130561068642;6016;6023.638581685091;6033.5474839387225;6040.184575082373;5828.883422331551;5954.8809023819285;5963.761449542035;5963.9403605964035;5967.164596956386;5135.820246291362;5238.842834714965;5253.185756207794;5257.290265814105;5267.499587539178;20591.07340169681;20789.792102078936;20869.8956505218;20946.790532094637;21061.578768424544;2132.509522809747;2175.7389113306344;2192.079326682781;2228.8216942644713;2235.6422972324513;13140.802887956688;13371.732565348639;13427.194368337834;13521.59435216945;13548.119372240872;1759.2083562396972;1801.1264536699691;1821.0712536606293;1827.798942116366;1936.1771247220015;58038.41961580372;60975.695121524106;61005.6949715253;61085;61811;9168.679096231599;9217.631294748135;9368.578413971387;9371.765705857408;9391.906080939103;12058.75882482363;12069.396530173457;12074.396280185956;12076.637700868989;12125.93937030327;7510.511816731895;7530.736424225126;7547.05661792276;7609.885851712229;7617.352525035323;7588.468807183444;7609.695612175562;7620.237976202336;7643.923560764432;7655.808604784925;5765.279340082489;5797.4782269595935;5802.738876750556;5807.421867219104;5894.292684877756;1589.2212815720213;1607.1321486397449;1700.9829901700825;1749.2915369275463;1755.1399814091258;934.6915517879083;944.9244060475284;944.9983018000804;946.5693109635099;977.008336538414;14952.327145278488;17314.91342543279;17832;18285.451436456926;18329.633407331778;862.719616124762;867.952262625549;927.4620719982394;943.3867985809142;959.3859929645033;9218.909149008425;9333.579988900516;9716.125548700651;9960.850587241197;9997.45014024221;363324;380976;388652;446114;449541;74586;77224.61387693138;77678.61160694274;79387.60306198432;79892;1155.5608868629868;1157.583270022791;1166.976660466786;1191.243560339191;1192.1714408486193;14441.422343106158;15384.769228461359;15416.768748468558;15674;15846.366145354057;30384.240393990327;30645.540316894818;31169;31179;32034.839825800722;38688.80655596704;39471;40182.79908600555;41224.587754122076;41737.79131104325;9137.314701397554;9264.39781414206;9280.303977201602;9383.202427793442;9388.95305523468;17063.317467301167;17343.653126937643;18002;18022.5494362642;18063.729044064086;14381.352839122474;14477.131372117707;14577.708445831237;14861.331240094005;14921.253937303309;2826.0673977586935;2872.4255148970706;9401;14493.53609886343;14550.12699238049;8581.01318348398;8752.78118047054;9775.169110625391;9899.406035637885;9929.156021738234;14949.252537373306;15011.324490398176;15161.620959476102;15498.84501154974;15585.22073896278;16087.75868361952;16155.192240388169;16373.42693005739;16463.094529800976;16478.176091195153 16:04:14 INFO - 2015-11-19 16:04:14,947 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1366.715347385946;1454.5345489443375;1519.7644365123406;1642.8685705143582;1762.7532145499633;6490.058941453486;7191.460640451967;7485.812854678636;7496.587687677183;7499.73750918718;7329.5602263864075;7463.21636228195;7465.440091993095;7477.663505142268;7481.962590187055;1948.0259870064967;1983.6726940054946;1986.1161783006496;2185.508260641355;2211.5797998380335;2258.446680563264;2309.58427483053;2311.0293676655847;2311.711036120485;2314.143766806276;2154.4290762947753;2173.858699184556;2212.5464279822677;2213.501962058532;2215.4904371994453;1928.7781905080885;1969.9606007879834;2205.1399954017947;2220.666899965002;2264.92072777452;2203.7575866654656;2234.530748542808;2242.5627002734473;2245.292732789166;2246.348558917928;7506.662200201004;7565.659545320419;7569.507981981209;7585.6207189640845;7588.544687318778;4528.660350473695;4621.74580398078;4624.653151013687;4630.21286381316;4630.467496237942;3007.699230076975;3047.4362242985117;3096.628404591441;3099.860506277223;3112.4553203189353;901.923336516397;916.3081873185702;934.6401635370363;936.3258453913172;970.1171933540514;9853.802923941481;9878.55546500402;9921.255905807093;9948.751281217956;9958.850617240747;4044.373122166069;4049.3521036633997;4106.137711080647;4109.137081212919;4138.5654506276405;8414.621342039623;8417.242448179693;8418.789530261793;8423.578821058922;8427.915720842835;4659.207934651117;4670.556297151765;4672.95327046732;4678.11115887987;4678.391809064799;4270.61564459194;4300.65594752419;4305.246581848227;4316.676249281317;4320.071184695243;3416.8655135199183;3451.8446669899913;3470.02839205023;3496.30073985204;3499.3876071687355;1055.846902199192;1064.7763969566322;1066.0831684751108;1069.5133714160195;1072.6996440996534;993.5926270229315;1002.0080120680474;1006.0291818395284;1130.6099395708468;1342.1142046249429;6579.506537009744;6691.832704182337;7097.92902070983;7322.450816188807;7325.523840950428;547.367790202317;561;563.2621266141368;565.3357305166413;568.2726110578466;5228.2680424740565;5237.004969055867;5249.947500525022;5273.077211487975;5273.209018647233;8654.6970856021;8686.261667758312;8832;8834.734957951272;8961;9012.143846334588;9053.456792592466;9143.268538516902;9146.451212927246;9146.771330716785;6772.288909664513;6854.314568543105;6867.278935711678;6920.619365934821;6928.341807528277;6972.477064220102;6985.1967023790885;7026.262242464468;7032.261612530815;7032.718691252293;7943.523388596472;8004.359651227771;8006.83986320282;8125.349972002344;8131.512109273463;8120.390970677105;8129.349652027942;8221.17788221173;8368.288695460711;8369.330453563578;6449.580777249464;6490.7079181436;6694.497912656668;6705.195376554844;6755.3582409671;7184.784456466315;7209.6395180241825;7210.96394518024;7212.170600381023;7220.422366210585;6656.23453302857;6793.490488213305;6826.965865170642;6944.51388402807;6970.546914450544;6659;6665.20017597872;6729.5625784323865;6772.55978361405;6785.7624983127325;6017.819465416044;6090.208272924669;6667.66661666905;6687.4984376171005;6701.631410272482;5763.913541296968;5804.303483581997;5909.320428150818;5919.733611987384;5923.170756094237;6113.938860611515;6148.661823599566;6159.6612186330185;6167.136600875791;6174.228221472316 16:04:14 INFO - 2015-11-19 16:04:14,951 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.9663437021928;312.3409605731905;314.4497130022461;326.43852573573463;328.86187801123526;6039.969800151004;6047.606905551142;6210.09953556734;6270.404311590397;6298.181236439268;5929.792457263997;6030.819075427733;6060.393960603938;6070.9392906070925;6091.14723938649;4371.388005679208;4484.753338566377;4627.329037289591;4630.398048253723;4632.907341853161;4519.050999290153;4594.517575654541;4607.424071991001;4634.698744581591;4636.721796692209;4529.932051019237;4535.410396648431;4546.272596384579;4555.04344087742;4568.84009059683;4589.747563883985;4598.632109431271;4601.953980460203;4608.700434471749;4609.492955774879;3926.685865130811;3975.920481590381;3976.065624578222;3988.2422339755353;3991.002249437641;5779.508741756914;5787.900298943229;5809.070548712186;5817.301923769132;5821.3014438267255;5386.299781028441;5387.218853266294;5389.380221274564;5389.487998640162;5391.24522566837;6239.96880015597;6276.780312689034;6345.984642457184;6469.611823290617;6475.222973243194;4365.105153443536;4384.101259241847;4385.6710746694125;4394.187075391031;4397.692161548693;6715.2613212546585;6787.966060169668;6795.252522222551;6796.898046529306;6807.659617019179;4399.758013309235;4532.388127602798;4605.401297831328;4605.884852878705;4609.792559334838;4576.816927322936;4577.977110114428;4579;4579.129965306581;4579.152856721517;5338.145896656516;5338.386085600128;5341.545968592635;5344.706041167696;5345.305110335709;6600.60396376219;6600.900986485207;6601.141851559267;6615.437687796495;6620.702068406837;1602.5032240005403;1613.8870279080586;1655.8261382554658;1657.312215430607;1663.6672665466956;1050.7268110291227;1054.3094273251033;1071.7642118733866;1117.932924024561;1133.5465813674596;6364.77723279683;6387.041943708388;6395.936040639534;6400.871982560323;6403.871922561616;6744.988251762176;6750.291219421988;6751.054852320679;6754.831129221809;6760.323967603299;6222;6386.137871387396;6389.936100639027;6390.329015453403;6392.520560957947;3638.9636103638622;3667.9083022924106;3678.8712395066045;3679.7240206984047;3680.5767336756617;7040.542364746275;7058.505904586732;7062.905249686307;7064.752733654297;7067.469939754436;6788;6933.791986240421;6939.063226464464;6944.756933507303;6947.131608548932;6800.149981252343;6891.827704307432;7020.438364930793;7033.331833475811;7046.718131274794;6624.337566243337;6792.592444453348;6803.353681400259;6808.38724514796;6833.658317084027;6722.159730033746;6727.663616819042;6752.358525940028;6758.256591774999;6758.8648227036165;6924.792256232321;7132.964335178499;7134.60759658203;7136.0723105995885;7145.392286735443;4359.803808828674;4366.2577361847925;4367.6724245682335;4374.540673229265;4380.474343078851;6724.630145342053;6726.596404215763;6729.596224226366;6739.663016849236;6754.358335958273;6429.421352078335;6580.30906754803;6590.406863382319;6593.934060659332;6618.371254730793;6573.901391479036;6583.177102862142;6590.077389165424;6594.703238354382;6596.901046484402;6457.806265812033;6477.967610161919;6499.902501462577;6516.380943810331;6516.989866570689;6505.60966342021;6565.179352580927;6625.105610742681;6633.900491492535;6642.6014439133805;6443.097966284818;6446.64543450096;6451.70967306463;6465.903011454738;6477.0608261800835 16:04:14 INFO - 2015-11-19 16:04:14,952 DEBUG : BROWSER_OUTPUT: |5;yui.html;747.5290566942826;822.0176888618101;836.3434703757555;837.907830138685;852.7654894903905;13992.300384980748;14341.426342946283;14361.138331700093;14370.784438233408;14680.926595367007;14172.362243699032;14232.217228052454;14306.713865722679;14357.712845743104;14374.353154108065;690.6581242285051;810.7081450677751;828.1097819843662;829.2453866981049;829.4276948905266;683.2450142592438;806.8830019647147;813.5281536708695;820.4871955028108;820.9876851847227;836.4019725895984;842.6797816829596;845.2899564365931;846.9237768600856;848.2662496940134;707.3421717802416;712.4976891291627;837.7361131243699;840.8318336332727;845.6490556419079;621.6425555305717;628.3967391304353;628.6825153297601;630.1461519640867;631.8388810853249;13096.214227146307;13233.933830330883;13276.269805160713;13278.800817987738;13296.26870522121;11581.884181158164;11645.6506304811;11902.404879755977;12048.277103373737;12520.123591348609;9336.348578173207;9339.579718912666;9480.95259523705;9720.319577629569;10051.698449046467;3523.8766643167623;3605.603383627799;3624.6737793598704;3717.275131349388;3719.5722491913507;49176.75411622919;49481;49657.255141172915;49664.50335496635;49760;8865.867011994826;9038;9080.918733186481;9444.244460443148;9664.75838104053;17760.733588996176;17781.822181778276;17787.377441789475;17790.377336793408;17808.287668493373;6996.580205187705;7063.858722825515;7281.308275713799;7334.816629584303;7474.439417043744;3998.820053097618;4046.089629833264;4110.979445102756;4118.361653943643;4197.4753155855515;3049.2224482756756;3089.335792804558;3098.2719061020643;3103.4103520331064;3103.90688279352;534.6765207049757;546.4808431989626;555.29754860102;558.9524890384281;559.9132134519156;591.4085914085914;621.2420846567181;648.6983552648041;649.4804156674736;677.9762708305284;8795.560221988875;8801;8819.603117859584;8873.73378798637;9019.909800902038;519.451978163034;546.2598179466845;598.2312728144313;623.329920335641;645.2192846655505;3820.1595648957186;3955.5451123120633;4270.167317373082;4400.22995975703;4459.665525085632;57774.4222557769;57829.421705782406;58112.4188758107;58140.41859581435;58312.12531812026;49598;52042.21936670953;52232;52384.47615523872;52480.212796808075;47161;47299.52700472951;47331.763341183076;47824.28263576049;48171.75914120407;8716.825663486568;8994.505302208441;9118.544072796467;9175.22010629104;9252.722418327192;32967;33132.50301245435;33260.1684957878;33343.49984750182;33711;45148.548514514434;46648;46817.53182468132;47028;47373.289400659705;10582.365058096539;10893.673189804318;10906.182036347464;11010.50452729613;11418.942905285421;11943.16397852142;12254.019678425526;12288.938555307166;12319.753604927671;12581.496740130293;11638;11734.70663233426;12069.517219311128;12147.210431321935;12492.625221243377;11442.94278528602;11782.646520604396;12048.397580121135;12435.06736994711;12448.564300249447;8392.083206574816;10603.787924241318;10798;11816.527338906692;12055.638330850088;9279.953600231956;9629.592850049652;9753;9804;10192.541335640062;9151.63393464254;9253.629854805733;9394;9630.807383852423;9660.613575456902 16:04:14 INFO - 2015-11-19 16:04:14,952 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1001 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: __startTimestamp1447977854939__endTimestamp 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5434.32 Median:5640.75 stddev:621.34 (11.0%) stddev-sans-first:597.47 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: Values: 3176.5 3219.2 3259.5 3261.1 3272.2 5197.8 5302.0 5386.2 5423.0 5460.8 5440.1 5469.0 5520.5 5527.6 5530.8 4770.6 4973.7 4998.1 5005.1 5121.0 5054.7 5070.0 5117.2 5123.0 5134.8 4883.6 4901.6 4992.9 5008.4 5022.6 4966.7 4991.8 5001.5 5003.9 5051.1 4951.2 4957.7 4980.4 5009.4 5032.5 5105.3 5144.2 5147.9 5178.2 5233.6 5881.4 5890.6 5895.7 5897.2 5902.3 5739.2 5931.2 5935.0 5941.2 5946.7 5410.1 5410.6 5411.3 5443.2 5494.7 5977.6 6024.0 6038.8 6061.4 6113.6 5549.3 5851.3 5855.3 5860.3 5900.4 5787.0 5845.2 5860.2 5894.1 5984.2 5834.1 5847.0 5862.9 5864.3 5876.3 5730.6 5876.6 5883.5 5883.6 5886.4 4308.8 4325.4 4371.5 4405.8 4407.0 3911.5 3919.0 3924.2 3931.5 3962.9 5480.1 5581.5 5598.4 5599.4 5613.1 5793.8 5800.9 5815.7 5831.8 5861.5 5551.3 5619.1 5623.6 5624.2 5630.3 5009.2 5143.8 5166.8 5168.9 5212.5 6020.5 6088.4 6092.0 6124.4 6136.5 5778.2 5810.7 5837.6 5842.5 5845.6 5905.5 6037.2 6063.5 6065.2 6071.8 5658.9 5666.9 5735.7 5741.0 5798.9 5844.5 5874.6 5878.9 5887.1 5892.1 5480.7 5782.9 5794.1 5806.7 5807.2 5616.2 5644.7 5652.1 5671.9 5732.6 5607.3 5636.4 5650.6 5656.1 5664.9 5640.7 5713.1 5747.9 5752.9 5777.7 5587.1 5602.3 5657.7 5692.7 5736.8 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,953 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45167.89 Median:31354.11 stddev:73963.63 (235.9%) stddev-sans-first:74095.28 16:04:14 INFO - 2015-11-19 16:04:14,955 DEBUG : BROWSER_OUTPUT: Values: 556.9 591.6 598.3 599.9 601.5 31534.5 39176.0 39559.0 39568.4 39703.0 41884.8 42222.0 42404.2 42427.0 42520.8 7681.7 7795.9 8780.3 8986.4 10254.5 10421.5 10530.9 10539.9 10543.5 10552.8 10498.1 10609.1 10617.1 10619.5 10621.3 7755.5 7937.2 10599.1 10875.3 10888.7 10341.4 10524.0 10530.1 10530.7 10532.2 39112.0 39573.2 39574.0 39638.0 39673.4 3218.2 3321.6 3493.2 3999.9 4083.2 29676.0 29864.6 30033.2 30066.7 30607.2 3894.3 3924.5 3985.2 3988.0 4019.4 261995.0 262142.0 262600.0 262628.0 264904.0 54210.7 54892.2 55034.4 55361.0 55931.7 52658.0 52759.0 53069.0 53201.5 53211.2 14965.9 15080.3 15097.7 15165.2 15167.8 11778.2 11833.0 11833.2 11839.9 11841.2 17987.6 18463.9 18692.8 18701.4 18754.2 7666.9 7781.0 7892.4 7929.8 8007.5 958.7 977.8 985.8 1014.1 1053.7 28094.9 28310.3 28740.3 28878.9 28884.6 1060.0 1080.7 1148.4 1194.2 1204.5 14898.9 14968.3 14980.8 15159.7 15163.2 299916.0 302912.0 303514.0 303945.0 303980.0 51549.5 52328.7 52448.0 52462.5 52463.5 1308.2 1327.9 1331.4 1334.0 1345.0 27434.6 28292.4 28374.9 28415.4 28627.1 45631.0 46204.8 46235.8 46295.8 46303.8 283735.0 284035.0 285027.0 285283.0 285402.0 27893.6 30797.8 31338.2 31370.0 31865.2 38083.4 38176.2 38718.2 39687.6 39703.4 33117.8 34479.7 35010.5 35350.6 35726.0 31121.4 34862.7 36331.5 36419.8 39515.2 35327.5 35664.6 36053.8 36152.1 36185.5 30587.4 32062.4 32269.0 32716.8 32771.5 24552.4 24750.5 24750.6 25042.2 25205.4 16:04:14 INFO - 2015-11-19 16:04:14,955 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,955 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24863.47 Median:209832.40 stddev:66758.71 (31.8%) stddev-sans-first:66920.62 16:04:14 INFO - 2015-11-19 16:04:14,956 DEBUG : BROWSER_OUTPUT: Values: 353.6 354.1 354.1 356.0 359.2 13713.4 14122.6 15940.5 16485.3 17601.8 16389.9 16551.2 17012.7 17412.7 17425.7 5244.3 5450.8 5454.9 5455.1 5458.7 3913.9 4049.1 5384.4 5931.1 6103.6 5996.1 6016.0 6023.6 6033.5 6040.2 5828.9 5954.9 5963.8 5963.9 5967.2 5135.8 5238.8 5253.2 5257.3 5267.5 20591.1 20789.8 20869.9 20946.8 21061.6 2132.5 2175.7 2192.1 2228.8 2235.6 13140.8 13371.7 13427.2 13521.6 13548.1 1759.2 1801.1 1821.1 1827.8 1936.2 58038.4 60975.7 61005.7 61085.0 61811.0 9168.7 9217.6 9368.6 9371.8 9391.9 12058.8 12069.4 12074.4 12076.6 12125.9 7510.5 7530.7 7547.1 7609.9 7617.4 7588.5 7609.7 7620.2 7643.9 7655.8 5765.3 5797.5 5802.7 5807.4 5894.3 1589.2 1607.1 1701.0 1749.3 1755.1 934.7 944.9 945.0 946.6 977.0 14952.3 17314.9 17832.0 18285.5 18329.6 862.7 868.0 927.5 943.4 959.4 9218.9 9333.6 9716.1 9960.9 9997.5 363324.0 380976.0 388652.0 446114.0 449541.0 74586.0 77224.6 77678.6 79387.6 79892.0 1155.6 1157.6 1167.0 1191.2 1192.2 14441.4 15384.8 15416.8 15674.0 15846.4 30384.2 30645.5 31169.0 31179.0 32034.8 38688.8 39471.0 40182.8 41224.6 41737.8 9137.3 9264.4 9280.3 9383.2 9389.0 17063.3 17343.7 18002.0 18022.5 18063.7 14381.4 14477.1 14577.7 14861.3 14921.3 2826.1 2872.4 9401.0 14493.5 14550.1 8581.0 8752.8 9775.2 9899.4 9929.2 14949.3 15011.3 15161.6 15498.8 15585.2 16087.8 16155.2 16373.4 16463.1 16478.2 16:04:14 INFO - 2015-11-19 16:04:14,956 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,956 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5195.71 Median:6311.90 stddev:2691.27 (42.6%) stddev-sans-first:2683.43 16:04:14 INFO - 2015-11-19 16:04:14,956 DEBUG : BROWSER_OUTPUT: Values: 1366.7 1454.5 1519.8 1642.9 1762.8 6490.1 7191.5 7485.8 7496.6 7499.7 7329.6 7463.2 7465.4 7477.7 7482.0 1948.0 1983.7 1986.1 2185.5 2211.6 2258.4 2309.6 2311.0 2311.7 2314.1 2154.4 2173.9 2212.5 2213.5 2215.5 1928.8 1970.0 2205.1 2220.7 2264.9 2203.8 2234.5 2242.6 2245.3 2246.3 7506.7 7565.7 7569.5 7585.6 7588.5 4528.7 4621.7 4624.7 4630.2 4630.5 3007.7 3047.4 3096.6 3099.9 3112.5 901.9 916.3 934.6 936.3 970.1 9853.8 9878.6 9921.3 9948.8 9958.9 4044.4 4049.4 4106.1 4109.1 4138.6 8414.6 8417.2 8418.8 8423.6 8427.9 4659.2 4670.6 4673.0 4678.1 4678.4 4270.6 4300.7 4305.2 4316.7 4320.1 3416.9 3451.8 3470.0 3496.3 3499.4 1055.8 1064.8 1066.1 1069.5 1072.7 993.6 1002.0 1006.0 1130.6 1342.1 6579.5 6691.8 7097.9 7322.5 7325.5 547.4 561.0 563.3 565.3 568.3 5228.3 5237.0 5249.9 5273.1 5273.2 8654.7 8686.3 8832.0 8834.7 8961.0 9012.1 9053.5 9143.3 9146.5 9146.8 6772.3 6854.3 6867.3 6920.6 6928.3 6972.5 6985.2 7026.3 7032.3 7032.7 7943.5 8004.4 8006.8 8125.3 8131.5 8120.4 8129.3 8221.2 8368.3 8369.3 6449.6 6490.7 6694.5 6705.2 6755.4 7184.8 7209.6 7211.0 7212.2 7220.4 6656.2 6793.5 6827.0 6944.5 6970.5 6659.0 6665.2 6729.6 6772.6 6785.8 6017.8 6090.2 6667.7 6687.5 6701.6 5763.9 5804.3 5909.3 5919.7 5923.2 6113.9 6148.7 6159.7 6167.1 6174.2 16:04:14 INFO - 2015-11-19 16:04:14,956 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,957 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5439.58 Median:6273.59 stddev:1682.72 (26.8%) stddev-sans-first:1642.73 16:04:14 INFO - 2015-11-19 16:04:14,957 DEBUG : BROWSER_OUTPUT: Values: 306.0 312.3 314.4 326.4 328.9 6040.0 6047.6 6210.1 6270.4 6298.2 5929.8 6030.8 6060.4 6070.9 6091.1 4371.4 4484.8 4627.3 4630.4 4632.9 4519.1 4594.5 4607.4 4634.7 4636.7 4529.9 4535.4 4546.3 4555.0 4568.8 4589.7 4598.6 4602.0 4608.7 4609.5 3926.7 3975.9 3976.1 3988.2 3991.0 5779.5 5787.9 5809.1 5817.3 5821.3 5386.3 5387.2 5389.4 5389.5 5391.2 6240.0 6276.8 6346.0 6469.6 6475.2 4365.1 4384.1 4385.7 4394.2 4397.7 6715.3 6788.0 6795.3 6796.9 6807.7 4399.8 4532.4 4605.4 4605.9 4609.8 4576.8 4578.0 4579.0 4579.1 4579.2 5338.1 5338.4 5341.5 5344.7 5345.3 6600.6 6600.9 6601.1 6615.4 6620.7 1602.5 1613.9 1655.8 1657.3 1663.7 1050.7 1054.3 1071.8 1117.9 1133.5 6364.8 6387.0 6395.9 6400.9 6403.9 6745.0 6750.3 6751.1 6754.8 6760.3 6222.0 6386.1 6389.9 6390.3 6392.5 3639.0 3667.9 3678.9 3679.7 3680.6 7040.5 7058.5 7062.9 7064.8 7067.5 6788.0 6933.8 6939.1 6944.8 6947.1 6800.1 6891.8 7020.4 7033.3 7046.7 6624.3 6792.6 6803.4 6808.4 6833.7 6722.2 6727.7 6752.4 6758.3 6758.9 6924.8 7133.0 7134.6 7136.1 7145.4 4359.8 4366.3 4367.7 4374.5 4380.5 6724.6 6726.6 6729.6 6739.7 6754.4 6429.4 6580.3 6590.4 6593.9 6618.4 6573.9 6583.2 6590.1 6594.7 6596.9 6457.8 6478.0 6499.9 6516.4 6517.0 6505.6 6565.2 6625.1 6633.9 6642.6 6443.1 6446.6 6451.7 6465.9 6477.1 16:04:14 INFO - 2015-11-19 16:04:14,957 DEBUG : BROWSER_OUTPUT: 16:04:14 INFO - 2015-11-19 16:04:14,957 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13914.89 Median:49712.25 stddev:16230.23 (32.6%) stddev-sans-first:16245.64 16:04:14 INFO - 2015-11-19 16:04:14,958 DEBUG : BROWSER_OUTPUT: Values: 747.5 822.0 836.3 837.9 852.8 13992.3 14341.4 14361.1 14370.8 14680.9 14172.4 14232.2 14306.7 14357.7 14374.4 690.7 810.7 828.1 829.2 829.4 683.2 806.9 813.5 820.5 821.0 836.4 842.7 845.3 846.9 848.3 707.3 712.5 837.7 840.8 845.6 621.6 628.4 628.7 630.1 631.8 13096.2 13233.9 13276.3 13278.8 13296.3 11581.9 11645.7 11902.4 12048.3 12520.1 9336.3 9339.6 9481.0 9720.3 10051.7 3523.9 3605.6 3624.7 3717.3 3719.6 49176.8 49481.0 49657.3 49664.5 49760.0 8865.9 9038.0 9080.9 9444.2 9664.8 17760.7 17781.8 17787.4 17790.4 17808.3 6996.6 7063.9 7281.3 7334.8 7474.4 3998.8 4046.1 4111.0 4118.4 4197.5 3049.2 3089.3 3098.3 3103.4 3103.9 534.7 546.5 555.3 559.0 559.9 591.4 621.2 648.7 649.5 678.0 8795.6 8801.0 8819.6 8873.7 9019.9 519.5 546.3 598.2 623.3 645.2 3820.2 3955.5 4270.2 4400.2 4459.7 57774.4 57829.4 58112.4 58140.4 58312.1 49598.0 52042.2 52232.0 52384.5 52480.2 47161.0 47299.5 47331.8 47824.3 48171.8 8716.8 8994.5 9118.5 9175.2 9252.7 32967.0 33132.5 33260.2 33343.5 33711.0 45148.5 46648.0 46817.5 47028.0 47373.3 10582.4 10893.7 10906.2 11010.5 11418.9 11943.2 12254.0 12288.9 12319.8 12581.5 11638.0 11734.7 12069.5 12147.2 12492.6 11442.9 11782.6 12048.4 12435.1 12448.6 8392.1 10603.8 10798.0 11816.5 12055.6 9280.0 9629.6 9753.0 9804.0 10192.5 9151.6 9253.6 9394.0 9630.8 9660.6 16:04:14 INFO - 2015-11-19 16:04:14,958 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:04:14 INFO - 2015-11-19 16:04:14,958 DEBUG : BROWSER_OUTPUT: 16:04:15 INFO - 2015-11-19 16:04:15,601 INFO : Browser exited with error code: 0 16:04:15 INFO - 2015-11-19 16:04:15,619 INFO : Completed test dromaeo_css (00:18:34) 16:04:15 INFO - 2015-11-19 16:04:15,619 INFO : Starting test kraken 16:04:15 INFO - 2015-11-19 16:04:15,619 DEBUG : operating with platform_type : linux_ 16:04:15 INFO - 2015-11-19 16:04:15,619 INFO : Initialising browser for kraken test... 16:04:15 INFO - 2015-11-19 16:04:15,626 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMwqyCM/profile http://localhost:40158/getInfo.html 16:04:19 INFO - 2015-11-19 16:04:19,372 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:04:19 INFO - 2015-11-19 16:04:19,372 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:04:19 INFO - 2015-11-19 16:04:19,372 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:04:19 INFO - 2015-11-19 16:04:19,372 DEBUG : BROWSER_OUTPUT: __metrics 16:04:19 INFO - 2015-11-19 16:04:19,489 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:04:20 INFO - 2015-11-19 16:04:20,694 INFO : Browser initialized. 16:04:20 INFO - 2015-11-19 16:04:20,695 INFO : Running cycle 1/1 for kraken test... 16:04:20 INFO - 2015-11-19 16:04:20,695 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMwqyCM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:04:32 INFO - 2015-11-19 16:04:32,479 DEBUG : BROWSER_OUTPUT: RSS: Main: 141987840 16:04:32 INFO - 2015-11-19 16:04:32,480 DEBUG : BROWSER_OUTPUT: 16:04:34 INFO - 2015-11-19 16:04:34,965 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40158/tests/kraken/driver.html?testName=audio-beat-detection) 16:04:35 INFO - 2015-11-19 16:04:35,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 174804992 16:04:35 INFO - 2015-11-19 16:04:35,547 DEBUG : BROWSER_OUTPUT: 16:04:38 INFO - 2015-11-19 16:04:38,114 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40158/tests/kraken/driver.html?testName=audio-dft) 16:04:38 INFO - 2015-11-19 16:04:38,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 167096320 16:04:38 INFO - 2015-11-19 16:04:38,680 DEBUG : BROWSER_OUTPUT: 16:04:41 INFO - 2015-11-19 16:04:41,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40158/tests/kraken/driver.html?testName=audio-fft) 16:04:42 INFO - 2015-11-19 16:04:42,050 DEBUG : BROWSER_OUTPUT: RSS: Main: 152305664 16:04:42 INFO - 2015-11-19 16:04:42,050 DEBUG : BROWSER_OUTPUT: 16:04:44 INFO - 2015-11-19 16:04:44,021 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40158/tests/kraken/driver.html?testName=audio-oscillator) 16:04:44 INFO - 2015-11-19 16:04:44,587 DEBUG : BROWSER_OUTPUT: RSS: Main: 153198592 16:04:44 INFO - 2015-11-19 16:04:44,588 DEBUG : BROWSER_OUTPUT: 16:04:46 INFO - 2015-11-19 16:04:46,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40158/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:47 INFO - 2015-11-19 16:04:47,301 DEBUG : BROWSER_OUTPUT: RSS: Main: 156536832 16:04:47 INFO - 2015-11-19 16:04:47,302 DEBUG : BROWSER_OUTPUT: 16:04:50 INFO - 2015-11-19 16:04:50,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40158/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:50 INFO - 2015-11-19 16:04:50,760 DEBUG : BROWSER_OUTPUT: RSS: Main: 154849280 16:04:50 INFO - 2015-11-19 16:04:50,760 DEBUG : BROWSER_OUTPUT: 16:04:54 INFO - 2015-11-19 16:04:54,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40158/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:54 INFO - 2015-11-19 16:04:54,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 156856320 16:04:54 INFO - 2015-11-19 16:04:54,741 DEBUG : BROWSER_OUTPUT: 16:04:57 INFO - 2015-11-19 16:04:57,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40158/tests/kraken/driver.html?testName=json-parse-financial) 16:04:58 INFO - 2015-11-19 16:04:58,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 155635712 16:04:58 INFO - 2015-11-19 16:04:58,071 DEBUG : BROWSER_OUTPUT: 16:04:59 INFO - 2015-11-19 16:04:59,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40158/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:05:00 INFO - 2015-11-19 16:05:00,208 DEBUG : BROWSER_OUTPUT: RSS: Main: 164970496 16:05:00 INFO - 2015-11-19 16:05:00,209 DEBUG : BROWSER_OUTPUT: 16:05:02 INFO - 2015-11-19 16:05:02,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:05:02 INFO - 2015-11-19 16:05:02,624 DEBUG : BROWSER_OUTPUT: RSS: Main: 163409920 16:05:02 INFO - 2015-11-19 16:05:02,625 DEBUG : BROWSER_OUTPUT: 16:05:04 INFO - 2015-11-19 16:05:04,684 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:05:05 INFO - 2015-11-19 16:05:05,265 DEBUG : BROWSER_OUTPUT: RSS: Main: 172331008 16:05:05 INFO - 2015-11-19 16:05:05,265 DEBUG : BROWSER_OUTPUT: 16:05:08 INFO - 2015-11-19 16:05:08,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:08 INFO - 2015-11-19 16:05:08,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 179372032 16:05:08 INFO - 2015-11-19 16:05:08,604 DEBUG : BROWSER_OUTPUT: 16:05:11 INFO - 2015-11-19 16:05:11,166 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:11 INFO - 2015-11-19 16:05:11,734 DEBUG : BROWSER_OUTPUT: RSS: Main: 179310592 16:05:11 INFO - 2015-11-19 16:05:11,735 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,388 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40158/tests/kraken/driver.html?testName=ai-astar) 16:05:13 INFO - 2015-11-19 16:05:13,638 DEBUG : BROWSER_OUTPUT: RSS: Main: 237318144 16:05:13 INFO - 2015-11-19 16:05:13,638 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,640 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:05:13 INFO - 2015-11-19 16:05:13,640 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:05:13 INFO - 2015-11-19 16:05:13,640 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:05:13 INFO - 2015-11-19 16:05:13,640 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:05:13 INFO - 2015-11-19 16:05:13,640 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;105;112;111;110;98;108;101;109 16:05:13 INFO - 2015-11-19 16:05:13,641 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;123;150;161;125;153;151;124;164;123 16:05:13 INFO - 2015-11-19 16:05:13,641 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;161;161;159;158;159;159;165;159;163 16:05:13 INFO - 2015-11-19 16:05:13,641 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;81;91;72;72;72;117;88;71 16:05:13 INFO - 2015-11-19 16:05:13,641 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;91;137;90;90;90;130;89;90;143 16:05:13 INFO - 2015-11-19 16:05:13,641 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;109;109;109;108;109;109 16:05:13 INFO - 2015-11-19 16:05:13,641 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;147;146;146;145;145;145;145;145;146 16:05:13 INFO - 2015-11-19 16:05:13,642 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;96;96;96;96;96 16:05:13 INFO - 2015-11-19 16:05:13,643 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;61;58;58;58;57;58;65;57;56 16:05:13 INFO - 2015-11-19 16:05:13,643 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;68;69;69;69;70;69;68;69 16:05:13 INFO - 2015-11-19 16:05:13,643 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;94;85;86;86;84;86;85;87;85 16:05:13 INFO - 2015-11-19 16:05:13,643 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;116;120;123;115;116;116;116;114;124 16:05:13 INFO - 2015-11-19 16:05:13,643 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;149;148;147;158;138;151;147;154;145 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;77;66;66;66;68;66;66;67;68;66 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,901 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: __startTimestamp1447977913639__endTimestamp 16:05:13 INFO - 2015-11-19 16:05:13,644 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,645 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:110.00 stddev:4.57 (4.2%) stddev-sans-first:4.81 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 105.0 112.0 111.0 110.0 98.0 108.0 101.0 109.0 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.50 Median:151.00 stddev:16.75 (11.1%) stddev-sans-first:17.48 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: Values: 151.0 123.0 150.0 161.0 125.0 153.0 151.0 124.0 164.0 123.0 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:2.21 (1.4%) stddev-sans-first:2.30 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: Values: 159.0 161.0 161.0 159.0 158.0 159.0 159.0 165.0 159.0 163.0 16:05:13 INFO - 2015-11-19 16:05:13,646 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:72.00 stddev:14.79 (20.5%) stddev-sans-first:15.26 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 81.0 91.0 72.0 72.0 72.0 117.0 88.0 71.0 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.00 Median:90.00 stddev:22.75 (25.3%) stddev-sans-first:23.56 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: Values: 90.0 91.0 137.0 90.0 90.0 90.0 130.0 89.0 90.0 143.0 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 109.0 109.0 109.0 108.0 109.0 109.0 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: Values: 145.0 147.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:05:13 INFO - 2015-11-19 16:05:13,647 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.00 stddev:2.59 (4.5%) stddev-sans-first:2.74 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: Values: 58.0 61.0 58.0 58.0 58.0 57.0 58.0 65.0 57.0 56.0 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 68.0 69.0 69.0 69.0 70.0 69.0 68.0 69.0 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.00 stddev:3.00 (3.5%) stddev-sans-first:2.96 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: Values: 83.0 94.0 85.0 86.0 86.0 84.0 86.0 85.0 87.0 85.0 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.20 Median:118.00 stddev:3.68 (3.1%) stddev-sans-first:3.63 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: Values: 122.0 116.0 120.0 123.0 115.0 116.0 116.0 116.0 114.0 124.0 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.70 Median:148.50 stddev:5.96 (4.0%) stddev-sans-first:5.64 16:05:13 INFO - 2015-11-19 16:05:13,648 DEBUG : BROWSER_OUTPUT: Values: 140.0 149.0 148.0 147.0 158.0 138.0 151.0 147.0 154.0 145.0 16:05:13 INFO - 2015-11-19 16:05:13,649 DEBUG : BROWSER_OUTPUT: 16:05:13 INFO - 2015-11-19 16:05:13,649 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:66.50 stddev:3.41 (5.1%) stddev-sans-first:0.88 16:05:13 INFO - 2015-11-19 16:05:13,649 DEBUG : BROWSER_OUTPUT: Values: 77.0 66.0 66.0 66.0 68.0 66.0 66.0 67.0 68.0 66.0 16:05:13 INFO - 2015-11-19 16:05:13,649 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:05:13 INFO - 2015-11-19 16:05:13,649 DEBUG : BROWSER_OUTPUT: 16:05:14 INFO - 2015-11-19 16:05:14,509 INFO : Browser exited with error code: 0 16:05:14 INFO - 2015-11-19 16:05:14,557 INFO : Completed test kraken (00:00:58) 16:05:14 INFO - 2015-11-19 16:05:14,557 INFO : Starting test v8_7 16:05:14 INFO - 2015-11-19 16:05:14,557 DEBUG : operating with platform_type : linux_ 16:05:14 INFO - 2015-11-19 16:05:14,557 INFO : Initialising browser for v8_7 test... 16:05:14 INFO - 2015-11-19 16:05:14,563 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvX3dnT/profile http://localhost:40158/getInfo.html 16:05:18 INFO - 2015-11-19 16:05:18,461 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:05:18 INFO - 2015-11-19 16:05:18,462 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:05:18 INFO - 2015-11-19 16:05:18,462 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:05:18 INFO - 2015-11-19 16:05:18,462 DEBUG : BROWSER_OUTPUT: __metrics 16:05:18 INFO - 2015-11-19 16:05:18,639 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:05:19 INFO - 2015-11-19 16:05:19,781 INFO : Browser initialized. 16:05:19 INFO - 2015-11-19 16:05:19,782 INFO : Running cycle 1/1 for v8_7 test... 16:05:19 INFO - 2015-11-19 16:05:19,782 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvX3dnT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:05:31 INFO - 2015-11-19 16:05:31,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 141594624 16:05:31 INFO - 2015-11-19 16:05:31,541 DEBUG : BROWSER_OUTPUT: 16:05:52 INFO - 2015-11-19 16:05:52,910 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40158/tests/v8_7/run.html (next: http://localhost:40158/tests/v8_7/run.html) 16:05:53 INFO - 2015-11-19 16:05:53,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 543690752 16:05:53 INFO - 2015-11-19 16:05:53,168 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,168 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:05:53 INFO - 2015-11-19 16:05:53,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:05:53 INFO - 2015-11-19 16:05:53,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:05:53 INFO - 2015-11-19 16:05:53,168 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |0;Richards;163.1055292774425 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.02405898331392 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.9002849002849 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5035.175879396985 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1104.9723756906078 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |5;Earley;778.2101167315175 16:05:53 INFO - 2015-11-19 16:05:53,169 DEBUG : BROWSER_OUTPUT: |6;Boyer;12084.33734939759 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: |7;RegExp;26868.42105263158 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: |8;Splay;596.3029218843172 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: __startTimestamp1447977953161__endTimestamp 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.11 Median:163.11 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: Values: 163.1 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,170 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.02 Median:194.02 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: Values: 194.0 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.90 Median:284.90 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: Values: 284.9 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5035.18 Median:5035.18 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: Values: 5035.2 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1104.97 Median:1104.97 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: Values: 1105.0 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:778.21 Median:778.21 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: Values: 778.2 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12084.34 Median:12084.34 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,171 DEBUG : BROWSER_OUTPUT: Values: 12084.3 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26868.42 Median:26868.42 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: Values: 26868.4 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:596.30 Median:596.30 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: Values: 596.3 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: Values: 4830.9 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:05:53 INFO - 2015-11-19 16:05:53,172 DEBUG : BROWSER_OUTPUT: 16:05:53 INFO - 2015-11-19 16:05:53,985 INFO : Browser exited with error code: 0 16:05:54 INFO - 2015-11-19 16:05:54,001 INFO : Completed test v8_7 (00:00:39) 16:05:54 INFO - 2015-11-19 16:05:54,211 INFO : Completed test suite (00:20:13) 16:05:54 INFO - 2015-11-19 16:05:54,211 DEBUG : Working with test: dromaeo_css 16:05:54 INFO - 2015-11-19 16:05:54,211 DEBUG : Generating results file: dromaeo_css 16:05:54 INFO - 2015-11-19 16:05:54,213 DEBUG : Working with test: kraken 16:05:54 INFO - 2015-11-19 16:05:54,213 DEBUG : Generating results file: kraken 16:05:54 INFO - 2015-11-19 16:05:54,213 INFO : javascript benchmark 16:05:54 INFO - 2015-11-19 16:05:54,213 DEBUG : Working with test: v8_7 16:05:54 INFO - 2015-11-19 16:05:54,213 DEBUG : Generating results file: v8_7 16:05:54 INFO - 2015-11-19 16:05:54,214 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:05:54 INFO - 2015-11-19 16:05:54,274 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 16:05:54 INFO - 2015-11-19 16:05:54,275 DEBUG : process_Request line: dromaeo_css 6966.22 graph.html#tests=[[72,64,35]] 16:05:54 INFO - 2015-11-19 16:05:54,275 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:05:54 INFO - 2015-11-19 16:05:54,309 DEBUG : process_Request line: kraken 1492.00 graph.html#tests=[[232,64,35]] 16:05:54 INFO - 2015-11-19 16:05:54,310 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:05:54 INFO - 2015-11-19 16:05:54,340 DEBUG : process_Request line: v8_7 21049.02 graph.html#tests=[[230,64,35]] 16:05:54 INFO - 2015-11-19 16:05:54,341 INFO : javascript benchmark 16:05:54 INFO - 2015-11-19 16:05:54,345 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3176.523521471779, 3219.1952011997, 3259.4621887388566, 3261.0542942546663, 3272.214668479565, 5197.766100525476, 5302.0, 5386.192071189319, 5422.972885135574, 5460.808871689496, 5440.102383106782, 5469.042917489443, 5520.530754885839, 5527.585431092664, 5530.778768849241, 4770.585997519849, 4973.6621305623385, 4998.125328067583, 5005.124103281929, 5121.0, 5054.721990290533, 5069.974650126745, 5117.181250999841, 5123.0, 5134.768935397916, 4883.633727470418, 4901.607871370282, 4992.850214493535, 5008.424031236418, 5022.6233032522705, 4966.652334336598, 4991.750412479361, 5001.474845141244, 5003.924941125886, 5051.090803655341, 4951.232558953332, 4957.727324997124, 4980.377452818398, 5009.373828271466, 5032.471590482984, 5105.33630628016, 5144.228365745131, 5147.948520514784, 5178.171492561172, 5233.5551478124025, 5881.382454842223, 5890.646561206341, 5895.675737834417, 5897.233359663259, 5902.291724993029, 5739.196512488255, 5931.169636250928, 5935.0, 5941.19793827832, 5946.672932988684, 5410.053240682904, 5410.56715462766, 5411.296531450926, 5443.2107344434835, 5494.697791621419, 5977.551683623746, 6023.969880150659, 6038.758449662052, 6061.424164704346, 6113.57204995655, 5549.306336707911, 5851.268591426072, 5855.3266374367495, 5860.296764388216, 5900.43945825146, 5787.016207244695, 5845.240118784531, 5860.208871802252, 5894.056950887837, 5984.162217289583, 5834.124881267759, 5847.0, 5862.941370586324, 5864.296284445893, 5876.294844618587, 5730.570207234474, 5876.559258055663, 5883.470487656132, 5883.617564858356, 5886.381929897385, 4308.806103725277, 4325.351197320427, 4371.540988196257, 4405.7797110145, 4407.030453300269, 3911.4523966644692, 3918.9804050979565, 3924.2151569685607, 3931.4692516509904, 3962.881113566598, 5480.095784195642, 5581.4976652100695, 5598.44015598445, 5599.412061733541, 5613.073842815971, 5793.826185214451, 5800.88398232033, 5815.709214539256, 5831.7667293307795, 5861.531077513789, 5551.278333816578, 5619.129034999582, 5623.606347555632, 5624.184493248456, 5630.268065151504, 5009.24861270805, 5143.7685304161405, 5166.767495462713, 5168.9483105168465, 5212.530872221519, 6020.488258497989, 6088.4215999479975, 6092.0, 6124.418180272867, 6136.5090792737365, 5778.219940308173, 5810.68041257718, 5837.562182836389, 5842.474177323891, 5845.561582881386, 5905.52755779528, 6037.18498002764, 6063.484603808725, 6065.2418447694035, 6071.817845464643, 5658.915116273177, 5666.886662266649, 5735.684537350486, 5741.0, 5798.942010580009, 5844.532437405082, 5874.647521148745, 5878.941210587839, 5887.087501437284, 5892.086726557391, 5480.698561579152, 5782.942170578241, 5794.1308803679785, 5806.68063256525, 5807.245058142415, 5616.21373007771, 5644.717764111861, 5652.0674088774895, 5671.858203544944, 5732.57005724564, 5607.327120745536, 5636.408177141505, 5650.604457687922, 5656.123300888369, 5664.915026274692, 5640.746166422603, 5713.114467257582, 5747.942520574741, 5752.9137062943255, 5777.6822274775295, 5587.0502014656795, 5602.299712535933, 5657.688827114548, 5692.743826527733, 5736.799212027559], "unit": "ms", "name": "dojo.html", "value": 5392.5720470772485}, {"lowerIsBetter": false, "replicates": [556.866776110615, 591.6213623281101, 598.2521847690384, 599.8920194365015, 601.5007543738698, 31534.526982095285, 39176.0, 39559.0, 39568.40647390291, 39702.99999999996, 41884.790576047155, 42222.0, 42404.151916961644, 42427.0, 42520.78739606297, 7681.731139410122, 7795.883061754078, 8780.25367843734, 8986.415882967618, 10254.487275636226, 10421.478926053705, 10530.947345263263, 10539.947300263488, 10543.525541350618, 10552.78894422111, 10498.107660848837, 10609.098226650744, 10617.0975467085, 10619.46902654868, 10621.256512044121, 7755.534667919943, 7937.166597507266, 10599.0990765785, 10875.347479151196, 10888.67333979974, 10341.431221282826, 10524.0, 10530.104941080017, 10530.684079477627, 10532.15742740579, 39112.0, 39573.20853582941, 39574.0, 39638.0, 39673.404898926536, 3218.211538173139, 3321.634620191777, 3493.2314890724, 3999.8600048998437, 4083.224187404384, 29676.0, 29864.55203171954, 30033.249168770955, 30066.699333006607, 30607.234819129477, 3894.2795582816984, 3924.548676902136, 3985.20295940813, 3987.9601203987877, 4019.3970904364287, 261995.0, 262142.0, 262600.0, 262628.0, 264904.0, 54210.72894635502, 54892.17661735077, 55034.44965550373, 55361.0, 55931.72034139803, 52658.0, 52759.0, 53069.0, 53201.46798531965, 53211.201831972554, 14965.92517037408, 15080.321385537678, 15097.698046039017, 15165.24173791328, 15167.772483412758, 11778.175527713149, 11833.0, 11833.230839995373, 11839.940800295943, 11841.230320029343, 17987.550311242325, 18463.90768046151, 18692.813071869106, 18701.438956831316, 18754.156062976926, 7666.923330766733, 7780.961095194488, 7892.368610511146, 7929.841403171904, 8007.519548827089, 958.7411398922252, 977.7653363192925, 985.8373368394278, 1014.0619926567895, 1053.7418332508585, 28094.893606405985, 28310.292242694097, 28740.281492962844, 28878.855605721837, 28884.566731499046, 1059.9841002384971, 1080.6541906589973, 1148.4315263944402, 1194.2476239968764, 1204.5030186866209, 14898.925505372405, 14968.326425310886, 14980.775288370683, 15159.696806064037, 15163.241837908061, 299916.0, 302912.0, 303514.0, 303945.0, 303980.0, 51549.48450515447, 52328.738356308735, 52448.0, 52462.47537524576, 52463.47536524586, 1308.2281453942219, 1327.8804907558367, 1331.3609467455483, 1333.9799903001463, 1345.0, 27434.5884811724, 28292.434151316447, 28374.85812570924, 28415.431691365644, 28627.14118576446, 45631.0, 46204.768976154905, 46235.76882115568, 46295.76852115853, 46303.76848115738, 283735.0, 284035.0, 285027.0, 285283.0, 285402.0, 27893.581596275668, 30797.8460107698, 31338.21654458657, 31370.0, 31865.203369915936, 38083.428748568236, 38176.23647526978, 38718.225635487695, 39687.60312396839, 39703.40444893387, 33117.83441082779, 34479.65520344764, 35010.474842876865, 35350.64649353473, 35726.0, 31121.37757244797, 34862.65137348594, 36331.45502817513, 36419.817900910326, 39515.209695805344, 35327.470087949216, 35664.64335356717, 36053.81973090118, 36152.096197595274, 36185.45721814122, 30587.388252235276, 32062.35875282528, 32269.0, 32716.83641581872, 32771.50842737313, 24552.38619034467, 24750.504989900463, 24750.628740568547, 25042.248732538053, 25205.369865753502], "unit": "ms", "name": "ext.html", "value": 18602.239703396244}, {"lowerIsBetter": false, "replicates": [353.6057296114833, 354.12001177074967, 354.12884304610657, 355.9911002224944, 359.18106716685946, 13713.3828977696, 14122.576322710334, 15940.521784346474, 16485.258163382645, 17601.82398176018, 16389.91805040976, 16551.172441377945, 17012.744808827876, 17412.651746965086, 17425.738613920803, 5244.292020577212, 5450.781968721247, 5454.890902181954, 5455.072637651589, 5458.727063646822, 3913.941290880639, 4049.1496785675045, 5384.380796208447, 5931.110333449974, 6103.572749907508, 5996.130561068642, 6016.0, 6023.638581685091, 6033.5474839387225, 6040.184575082373, 5828.883422331551, 5954.8809023819285, 5963.761449542035, 5963.9403605964035, 5967.164596956386, 5135.820246291362, 5238.842834714965, 5253.185756207794, 5257.290265814105, 5267.499587539178, 20591.07340169681, 20789.792102078936, 20869.8956505218, 20946.790532094637, 21061.578768424544, 2132.509522809747, 2175.7389113306344, 2192.079326682781, 2228.8216942644713, 2235.6422972324513, 13140.802887956688, 13371.732565348639, 13427.194368337834, 13521.59435216945, 13548.119372240872, 1759.2083562396972, 1801.1264536699691, 1821.0712536606293, 1827.798942116366, 1936.1771247220015, 58038.41961580372, 60975.695121524106, 61005.6949715253, 61085.0, 61811.0, 9168.679096231599, 9217.631294748135, 9368.578413971387, 9371.765705857408, 9391.906080939103, 12058.75882482363, 12069.396530173457, 12074.396280185956, 12076.637700868989, 12125.93937030327, 7510.511816731895, 7530.736424225126, 7547.05661792276, 7609.885851712229, 7617.352525035323, 7588.468807183444, 7609.695612175562, 7620.237976202336, 7643.923560764432, 7655.808604784925, 5765.279340082489, 5797.4782269595935, 5802.738876750556, 5807.421867219104, 5894.292684877756, 1589.2212815720213, 1607.1321486397449, 1700.9829901700825, 1749.2915369275463, 1755.1399814091258, 934.6915517879083, 944.9244060475284, 944.9983018000804, 946.5693109635099, 977.008336538414, 14952.327145278488, 17314.91342543279, 17832.0, 18285.451436456926, 18329.633407331778, 862.719616124762, 867.952262625549, 927.4620719982394, 943.3867985809142, 959.3859929645033, 9218.909149008425, 9333.579988900516, 9716.125548700651, 9960.850587241197, 9997.45014024221, 363324.0, 380976.0, 388652.0, 446114.0, 449541.0, 74586.0, 77224.61387693138, 77678.61160694274, 79387.60306198432, 79892.0, 1155.5608868629868, 1157.583270022791, 1166.976660466786, 1191.243560339191, 1192.1714408486193, 14441.422343106158, 15384.769228461359, 15416.768748468558, 15674.0, 15846.366145354057, 30384.240393990327, 30645.540316894818, 31169.0, 31179.0, 32034.839825800722, 38688.80655596704, 39471.0, 40182.79908600555, 41224.587754122076, 41737.79131104325, 9137.314701397554, 9264.39781414206, 9280.303977201602, 9383.202427793442, 9388.95305523468, 17063.317467301167, 17343.653126937643, 18002.0, 18022.5494362642, 18063.729044064086, 14381.352839122474, 14477.131372117707, 14577.708445831237, 14861.331240094005, 14921.253937303309, 2826.0673977586935, 2872.4255148970706, 9401.0, 14493.53609886343, 14550.12699238049, 8581.01318348398, 8752.78118047054, 9775.169110625391, 9899.406035637885, 9929.156021738234, 14949.252537373306, 15011.324490398176, 15161.620959476102, 15498.84501154974, 15585.22073896278, 16087.75868361952, 16155.192240388169, 16373.42693005739, 16463.094529800976, 16478.176091195153], "unit": "ms", "name": "jquery.html", "value": 8814.777092306811}, {"lowerIsBetter": false, "replicates": [1366.715347385946, 1454.5345489443375, 1519.7644365123406, 1642.8685705143582, 1762.7532145499633, 6490.058941453486, 7191.460640451967, 7485.812854678636, 7496.587687677183, 7499.73750918718, 7329.5602263864075, 7463.21636228195, 7465.440091993095, 7477.663505142268, 7481.962590187055, 1948.0259870064967, 1983.6726940054946, 1986.1161783006496, 2185.508260641355, 2211.5797998380335, 2258.446680563264, 2309.58427483053, 2311.0293676655847, 2311.711036120485, 2314.143766806276, 2154.4290762947753, 2173.858699184556, 2212.5464279822677, 2213.501962058532, 2215.4904371994453, 1928.7781905080885, 1969.9606007879834, 2205.1399954017947, 2220.666899965002, 2264.92072777452, 2203.7575866654656, 2234.530748542808, 2242.5627002734473, 2245.292732789166, 2246.348558917928, 7506.662200201004, 7565.659545320419, 7569.507981981209, 7585.6207189640845, 7588.544687318778, 4528.660350473695, 4621.74580398078, 4624.653151013687, 4630.21286381316, 4630.467496237942, 3007.699230076975, 3047.4362242985117, 3096.628404591441, 3099.860506277223, 3112.4553203189353, 901.923336516397, 916.3081873185702, 934.6401635370363, 936.3258453913172, 970.1171933540514, 9853.802923941481, 9878.55546500402, 9921.255905807093, 9948.751281217956, 9958.850617240747, 4044.373122166069, 4049.3521036633997, 4106.137711080647, 4109.137081212919, 4138.5654506276405, 8414.621342039623, 8417.242448179693, 8418.789530261793, 8423.578821058922, 8427.915720842835, 4659.207934651117, 4670.556297151765, 4672.95327046732, 4678.11115887987, 4678.391809064799, 4270.61564459194, 4300.65594752419, 4305.246581848227, 4316.676249281317, 4320.071184695243, 3416.8655135199183, 3451.8446669899913, 3470.02839205023, 3496.30073985204, 3499.3876071687355, 1055.846902199192, 1064.7763969566322, 1066.0831684751108, 1069.5133714160195, 1072.6996440996534, 993.5926270229315, 1002.0080120680474, 1006.0291818395284, 1130.6099395708468, 1342.1142046249429, 6579.506537009744, 6691.832704182337, 7097.92902070983, 7322.450816188807, 7325.523840950428, 547.367790202317, 561.0, 563.2621266141368, 565.3357305166413, 568.2726110578466, 5228.2680424740565, 5237.004969055867, 5249.947500525022, 5273.077211487975, 5273.209018647233, 8654.6970856021, 8686.261667758312, 8832.0, 8834.734957951272, 8961.0, 9012.143846334588, 9053.456792592466, 9143.268538516902, 9146.451212927246, 9146.771330716785, 6772.288909664513, 6854.314568543105, 6867.278935711678, 6920.619365934821, 6928.341807528277, 6972.477064220102, 6985.1967023790885, 7026.262242464468, 7032.261612530815, 7032.718691252293, 7943.523388596472, 8004.359651227771, 8006.83986320282, 8125.349972002344, 8131.512109273463, 8120.390970677105, 8129.349652027942, 8221.17788221173, 8368.288695460711, 8369.330453563578, 6449.580777249464, 6490.7079181436, 6694.497912656668, 6705.195376554844, 6755.3582409671, 7184.784456466315, 7209.6395180241825, 7210.96394518024, 7212.170600381023, 7220.422366210585, 6656.23453302857, 6793.490488213305, 6826.965865170642, 6944.51388402807, 6970.546914450544, 6659.0, 6665.20017597872, 6729.5625784323865, 6772.55978361405, 6785.7624983127325, 6017.819465416044, 6090.208272924669, 6667.66661666905, 6687.4984376171005, 6701.631410272482, 5763.913541296968, 5804.303483581997, 5909.320428150818, 5919.733611987384, 5923.170756094237, 6113.938860611515, 6148.661823599566, 6159.6612186330185, 6167.136600875791, 6174.228221472316], "unit": "ms", "name": "mootools.html", "value": 4213.309014262774}, {"lowerIsBetter": false, "replicates": [305.9663437021928, 312.3409605731905, 314.4497130022461, 326.43852573573463, 328.86187801123526, 6039.969800151004, 6047.606905551142, 6210.09953556734, 6270.404311590397, 6298.181236439268, 5929.792457263997, 6030.819075427733, 6060.393960603938, 6070.9392906070925, 6091.14723938649, 4371.388005679208, 4484.753338566377, 4627.329037289591, 4630.398048253723, 4632.907341853161, 4519.050999290153, 4594.517575654541, 4607.424071991001, 4634.698744581591, 4636.721796692209, 4529.932051019237, 4535.410396648431, 4546.272596384579, 4555.04344087742, 4568.84009059683, 4589.747563883985, 4598.632109431271, 4601.953980460203, 4608.700434471749, 4609.492955774879, 3926.685865130811, 3975.920481590381, 3976.065624578222, 3988.2422339755353, 3991.002249437641, 5779.508741756914, 5787.900298943229, 5809.070548712186, 5817.301923769132, 5821.3014438267255, 5386.299781028441, 5387.218853266294, 5389.380221274564, 5389.487998640162, 5391.24522566837, 6239.96880015597, 6276.780312689034, 6345.984642457184, 6469.611823290617, 6475.222973243194, 4365.105153443536, 4384.101259241847, 4385.6710746694125, 4394.187075391031, 4397.692161548693, 6715.2613212546585, 6787.966060169668, 6795.252522222551, 6796.898046529306, 6807.659617019179, 4399.758013309235, 4532.388127602798, 4605.401297831328, 4605.884852878705, 4609.792559334838, 4576.816927322936, 4577.977110114428, 4579.0, 4579.129965306581, 4579.152856721517, 5338.145896656516, 5338.386085600128, 5341.545968592635, 5344.706041167696, 5345.305110335709, 6600.60396376219, 6600.900986485207, 6601.141851559267, 6615.437687796495, 6620.702068406837, 1602.5032240005403, 1613.8870279080586, 1655.8261382554658, 1657.312215430607, 1663.6672665466956, 1050.7268110291227, 1054.3094273251033, 1071.7642118733866, 1117.932924024561, 1133.5465813674596, 6364.77723279683, 6387.041943708388, 6395.936040639534, 6400.871982560323, 6403.871922561616, 6744.988251762176, 6750.291219421988, 6751.054852320679, 6754.831129221809, 6760.323967603299, 6222.0, 6386.137871387396, 6389.936100639027, 6390.329015453403, 6392.520560957947, 3638.9636103638622, 3667.9083022924106, 3678.8712395066045, 3679.7240206984047, 3680.5767336756617, 7040.542364746275, 7058.505904586732, 7062.905249686307, 7064.752733654297, 7067.469939754436, 6788.0, 6933.791986240421, 6939.063226464464, 6944.756933507303, 6947.131608548932, 6800.149981252343, 6891.827704307432, 7020.438364930793, 7033.331833475811, 7046.718131274794, 6624.337566243337, 6792.592444453348, 6803.353681400259, 6808.38724514796, 6833.658317084027, 6722.159730033746, 6727.663616819042, 6752.358525940028, 6758.256591774999, 6758.8648227036165, 6924.792256232321, 7132.964335178499, 7134.60759658203, 7136.0723105995885, 7145.392286735443, 4359.803808828674, 4366.2577361847925, 4367.6724245682335, 4374.540673229265, 4380.474343078851, 6724.630145342053, 6726.596404215763, 6729.596224226366, 6739.663016849236, 6754.358335958273, 6429.421352078335, 6580.30906754803, 6590.406863382319, 6593.934060659332, 6618.371254730793, 6573.901391479036, 6583.177102862142, 6590.077389165424, 6594.703238354382, 6596.901046484402, 6457.806265812033, 6477.967610161919, 6499.902501462577, 6516.380943810331, 6516.989866570689, 6505.60966342021, 6565.179352580927, 6625.105610742681, 6633.900491492535, 6642.6014439133805, 6443.097966284818, 6446.64543450096, 6451.70967306463, 6465.903011454738, 6477.0608261800835], "unit": "ms", "name": "prototype.html", "value": 4883.347727773742}, {"lowerIsBetter": false, "replicates": [747.5290566942826, 822.0176888618101, 836.3434703757555, 837.907830138685, 852.7654894903905, 13992.300384980748, 14341.426342946283, 14361.138331700093, 14370.784438233408, 14680.926595367007, 14172.362243699032, 14232.217228052454, 14306.713865722679, 14357.712845743104, 14374.353154108065, 690.6581242285051, 810.7081450677751, 828.1097819843662, 829.2453866981049, 829.4276948905266, 683.2450142592438, 806.8830019647147, 813.5281536708695, 820.4871955028108, 820.9876851847227, 836.4019725895984, 842.6797816829596, 845.2899564365931, 846.9237768600856, 848.2662496940134, 707.3421717802416, 712.4976891291627, 837.7361131243699, 840.8318336332727, 845.6490556419079, 621.6425555305717, 628.3967391304353, 628.6825153297601, 630.1461519640867, 631.8388810853249, 13096.214227146307, 13233.933830330883, 13276.269805160713, 13278.800817987738, 13296.26870522121, 11581.884181158164, 11645.6506304811, 11902.404879755977, 12048.277103373737, 12520.123591348609, 9336.348578173207, 9339.579718912666, 9480.95259523705, 9720.319577629569, 10051.698449046467, 3523.8766643167623, 3605.603383627799, 3624.6737793598704, 3717.275131349388, 3719.5722491913507, 49176.75411622919, 49481.0, 49657.255141172915, 49664.50335496635, 49760.0, 8865.867011994826, 9038.0, 9080.918733186481, 9444.244460443148, 9664.75838104053, 17760.733588996176, 17781.822181778276, 17787.377441789475, 17790.377336793408, 17808.287668493373, 6996.580205187705, 7063.858722825515, 7281.308275713799, 7334.816629584303, 7474.439417043744, 3998.820053097618, 4046.089629833264, 4110.979445102756, 4118.361653943643, 4197.4753155855515, 3049.2224482756756, 3089.335792804558, 3098.2719061020643, 3103.4103520331064, 3103.90688279352, 534.6765207049757, 546.4808431989626, 555.29754860102, 558.9524890384281, 559.9132134519156, 591.4085914085914, 621.2420846567181, 648.6983552648041, 649.4804156674736, 677.9762708305284, 8795.560221988875, 8801.0, 8819.603117859584, 8873.73378798637, 9019.909800902038, 519.451978163034, 546.2598179466845, 598.2312728144313, 623.329920335641, 645.2192846655505, 3820.1595648957186, 3955.5451123120633, 4270.167317373082, 4400.22995975703, 4459.665525085632, 57774.4222557769, 57829.421705782406, 58112.4188758107, 58140.41859581435, 58312.12531812026, 49598.0, 52042.21936670953, 52232.0, 52384.47615523872, 52480.212796808075, 47161.0, 47299.52700472951, 47331.763341183076, 47824.28263576049, 48171.75914120407, 8716.825663486568, 8994.505302208441, 9118.544072796467, 9175.22010629104, 9252.722418327192, 32967.0, 33132.50301245435, 33260.1684957878, 33343.49984750182, 33711.0, 45148.548514514434, 46648.0, 46817.53182468132, 47028.0, 47373.289400659705, 10582.365058096539, 10893.673189804318, 10906.182036347464, 11010.50452729613, 11418.942905285421, 11943.16397852142, 12254.019678425526, 12288.938555307166, 12319.753604927671, 12581.496740130293, 11638.0, 11734.70663233426, 12069.517219311128, 12147.210431321935, 12492.625221243377, 11442.94278528602, 11782.646520604396, 12048.397580121135, 12435.06736994711, 12448.564300249447, 8392.083206574816, 10603.787924241318, 10798.0, 11816.527338906692, 12055.638330850088, 9279.953600231956, 9629.592850049652, 9753.0, 9804.0, 10192.541335640062, 9151.63393464254, 9253.629854805733, 9394.0, 9630.807383852423, 9660.613575456902], "unit": "ms", "name": "yui.html", "value": 6281.012380393252}], "name": "dromaeo_css", "value": 6966.2206987813315}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 112.0, 111.0, 110.0, 98.0, 108.0, 101.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [151.0, 123.0, 150.0, 161.0, 125.0, 153.0, 151.0, 124.0, 164.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.5}, {"lowerIsBetter": true, "replicates": [159.0, 161.0, 161.0, 159.0, 158.0, 159.0, 159.0, 165.0, 159.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 81.0, 91.0, 72.0, 72.0, 72.0, 117.0, 88.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [90.0, 91.0, 137.0, 90.0, 90.0, 90.0, 130.0, 89.0, 90.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 104.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 147.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [58.0, 61.0, 58.0, 58.0, 58.0, 57.0, 58.0, 65.0, 57.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 58.6}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 68.0, 69.0, 69.0, 69.0, 70.0, 69.0, 68.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.9}, {"lowerIsBetter": true, "replicates": [83.0, 94.0, 85.0, 86.0, 86.0, 84.0, 86.0, 85.0, 87.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.1}, {"lowerIsBetter": true, "replicates": [122.0, 116.0, 120.0, 123.0, 115.0, 116.0, 116.0, 116.0, 114.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.2}, {"lowerIsBetter": true, "replicates": [140.0, 149.0, 148.0, 147.0, 158.0, 138.0, 151.0, 147.0, 154.0, 145.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 147.7}, {"lowerIsBetter": true, "replicates": [77.0, 66.0, 66.0, 66.0, 68.0, 66.0, 66.0, 67.0, 68.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.6}], "name": "kraken", "value": 1492.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.1055292774425], "unit": "score", "name": "Richards", "value": 216.43656200000007}, {"lowerIsBetter": false, "replicates": [194.02405898331392], "unit": "score", "name": "DeltaBlue", "value": 340.772172}, {"lowerIsBetter": false, "replicates": [284.9002849002849], "unit": "score", "name": "Encrypt", "value": 934.2953100000001}, {"lowerIsBetter": false, "replicates": [5035.175879396985], "unit": "score", "name": "Decrypt", "value": 52.864290419161705}, {"lowerIsBetter": false, "replicates": [1104.9723756906078], "unit": "score", "name": "RayTrace", "value": 669.6900450000003}, {"lowerIsBetter": false, "replicates": [778.2101167315175], "unit": "score", "name": "Earley", "value": 856.4049549999997}, {"lowerIsBetter": false, "replicates": [12084.33734939759], "unit": "score", "name": "Boyer", "value": 55.150976071784655}, {"lowerIsBetter": false, "replicates": [26868.42105263158], "unit": "score", "name": "RegExp", "value": 33.90541625857002}, {"lowerIsBetter": false, "replicates": [596.3029218843172], "unit": "score", "name": "Splay", "value": 136.66040700000002}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21049.01632793591}]} 16:05:54 INFO - RETURN: dromaeo_css: 6966.22 16:05:54 INFO - RETURN: kraken: 1492.00 16:05:54 INFO - RETURN: v8_7: 21049.02 16:05:54 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "21049.02"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1492.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6966.22"}}} 16:05:54 INFO - Return code: 0 16:05:54 INFO - # TBPL SUCCESS # 16:05:54 INFO - Running post-action listener: _resource_record_post_action 16:05:54 INFO - Running post-run listener: _resource_record_post_run 16:05:55 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 10395648; Write bytes: 395673600; Read time: 3052; Write time: 227240 16:05:55 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 93843456; Read time: 0; Write time: 67568 16:05:55 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 8241152; Write bytes: 301830144; Read time: 2964; Write time: 159672 16:05:55 INFO - Running post-run listener: _upload_blobber_files 16:05:55 INFO - Blob upload gear active. 16:05:55 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:05:55 INFO - Copying logs to upload dir... 16:05:55 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.321398 ========= master_lag: 59.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 3 secs) (at 2015-11-19 16:06:54.712253) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 16:06:54.719297) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009912 build_url: 'https://queue.taskcluster.net/v1/task/7Yx0j8-eRLypCNHAI1pBYA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.74 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 16:06:55.471254) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 16:06:55.471619) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1447976687.721153-1282521142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 16:06:55.777729) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 16:06:55.778104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 16:06:55.778557) ========= ========= Total master_lag: 60.34 =========