builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1448011955.49 results: success (0) buildid: 20151119233015 builduid: 37107d1fb2074cd49e59431bb444ffd8 revision: 1b2e15608f34fef0f23369731c80138f55f00cf2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.495098) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.495607) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.495924) ========= 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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006419 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.539157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.539454) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.562451) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.562779) ========= 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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004643 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.612542) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.612826) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.613202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.613474) ========= 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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-20 01:32:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2015-11-20 01:32:35 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.186670 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.812983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.813325) ========= 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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016200 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.862933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:35.863306) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1b2e15608f34fef0f23369731c80138f55f00cf2 --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 1b2e15608f34fef0f23369731c80138f55f00cf2 --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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-20 01:32:36,102 truncating revision to first 12 chars 2015-11-20 01:32:36,102 Setting DEBUG logging. 2015-11-20 01:32:36,102 attempt 1/10 2015-11-20 01:32:36,102 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1b2e15608f34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-20 01:32:36,761 unpacking tar archive at: fx-team-1b2e15608f34/testing/mozharness/ program finished with exit code 0 elapsedTime=0.964645 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:36.840847) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:36.841181) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:36.849626) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:36.849990) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 01:32:36.850468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-11-20 01:32:36.851096) ========= /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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:32:37 INFO - MultiFileLogger online at 20151120 01:32:37 in /builds/slave/test-pgo 01:32:37 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 01:32:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:32:37 INFO - {'append_to_log': False, 01:32:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:32:37 INFO - 'blob_upload_branch': 'Fx-Team', 01:32:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:32:37 INFO - 'branch': 'Fx-Team', 01:32:37 INFO - 'buildbot_json_path': 'buildprops.json', 01:32:37 INFO - 'config_files': ('talos/linux_config.py',), 01:32:37 INFO - 'default_actions': ('clobber', 01:32:37 INFO - 'read-buildbot-config', 01:32:37 INFO - 'download-and-extract', 01:32:37 INFO - 'populate-webroot', 01:32:37 INFO - 'create-virtualenv', 01:32:37 INFO - 'install', 01:32:37 INFO - 'run-tests'), 01:32:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:32:37 INFO - 'download_minidump_stackwalk': True, 01:32:37 INFO - 'download_symbols': 'ondemand', 01:32:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:32:37 INFO - 'tooltool.py': '/tools/tooltool.py', 01:32:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:32:37 INFO - '/tools/misc-python/virtualenv.py')}, 01:32:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:32:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:32:37 INFO - 'installer_path': 'installer.exe', 01:32:37 INFO - 'log_level': 'info', 01:32:37 INFO - 'log_name': 'talos', 01:32:37 INFO - 'log_to_console': True, 01:32:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:32:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:32:37 INFO - 'opt_config_files': (), 01:32:37 INFO - 'pip_index': False, 01:32:37 INFO - 'sps_profile': False, 01:32:37 INFO - 'sps_profile_interval': 0, 01:32:37 INFO - 'suite': 'dromaeojs', 01:32:37 INFO - 'system_bits': '64', 01:32:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:32:37 INFO - 'title': 'talos-linux64-ix-008', 01:32:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:32:37 INFO - 'use_talos_json': True, 01:32:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:32:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:32:37 INFO - 'work_dir': 'build'} 01:32:37 INFO - ##### 01:32:37 INFO - ##### Running clobber step. 01:32:37 INFO - ##### 01:32:37 INFO - Running pre-action listener: _resource_record_pre_action 01:32:37 INFO - Running main action method: clobber 01:32:37 INFO - rmtree: /builds/slave/test-pgo/build 01:32:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:32:38 INFO - Running post-action listener: _resource_record_post_action 01:32:38 INFO - ##### 01:32:38 INFO - ##### Running read-buildbot-config step. 01:32:38 INFO - ##### 01:32:38 INFO - Running pre-action listener: _resource_record_pre_action 01:32:38 INFO - Running main action method: read_buildbot_config 01:32:38 INFO - Using buildbot properties: 01:32:38 INFO - { 01:32:38 INFO - "properties": { 01:32:38 INFO - "buildnumber": 794, 01:32:38 INFO - "product": "firefox", 01:32:38 INFO - "script_repo_revision": "production", 01:32:38 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:32:38 INFO - "repository": "", 01:32:38 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 01:32:38 INFO - "buildid": "20151119233015", 01:32:38 INFO - "slavename": "talos-linux64-ix-008", 01:32:38 INFO - "pgo_build": "True", 01:32:38 INFO - "basedir": "/builds/slave/test-pgo", 01:32:38 INFO - "project": "", 01:32:38 INFO - "platform": "ubuntu64_hw", 01:32:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:32:38 INFO - "slavebuilddir": "test", 01:32:38 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 01:32:38 INFO - "branch": "fx-team", 01:32:38 INFO - "repo_path": "integration/fx-team", 01:32:38 INFO - "stage_platform": "linux64-pgo", 01:32:38 INFO - "builduid": "37107d1fb2074cd49e59431bb444ffd8", 01:32:38 INFO - "revision": "1b2e15608f34fef0f23369731c80138f55f00cf2" 01:32:38 INFO - }, 01:32:38 INFO - "sourcestamp": { 01:32:38 INFO - "repository": "", 01:32:38 INFO - "hasPatch": false, 01:32:38 INFO - "project": "", 01:32:38 INFO - "branch": "fx-team-linux64-pgo-talos", 01:32:38 INFO - "changes": [ 01:32:38 INFO - { 01:32:38 INFO - "category": null, 01:32:38 INFO - "files": [ 01:32:38 INFO - { 01:32:38 INFO - "url": null, 01:32:38 INFO - "name": "https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 01:32:38 INFO - } 01:32:38 INFO - ], 01:32:38 INFO - "repository": "", 01:32:38 INFO - "rev": "1b2e15608f34fef0f23369731c80138f55f00cf2", 01:32:38 INFO - "who": "sendchange", 01:32:38 INFO - "when": 1448011885, 01:32:38 INFO - "number": 6687652, 01:32:38 INFO - "comments": "", 01:32:38 INFO - "project": "", 01:32:38 INFO - "at": "Fri 20 Nov 2015 01:31:25", 01:32:38 INFO - "branch": "fx-team-linux64-pgo-talos", 01:32:38 INFO - "revlink": "", 01:32:38 INFO - "properties": [ 01:32:38 INFO - [ 01:32:38 INFO - "buildid", 01:32:38 INFO - "20151119233015", 01:32:38 INFO - "Change" 01:32:38 INFO - ], 01:32:38 INFO - [ 01:32:38 INFO - "builduid", 01:32:38 INFO - "37107d1fb2074cd49e59431bb444ffd8", 01:32:38 INFO - "Change" 01:32:38 INFO - ], 01:32:38 INFO - [ 01:32:38 INFO - "pgo_build", 01:32:38 INFO - "True", 01:32:38 INFO - "Change" 01:32:38 INFO - ] 01:32:38 INFO - ], 01:32:38 INFO - "revision": "1b2e15608f34fef0f23369731c80138f55f00cf2" 01:32:38 INFO - } 01:32:38 INFO - ], 01:32:38 INFO - "revision": "1b2e15608f34fef0f23369731c80138f55f00cf2" 01:32:38 INFO - } 01:32:38 INFO - } 01:32:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 01:32:38 INFO - Running post-action listener: _resource_record_post_action 01:32:38 INFO - ##### 01:32:38 INFO - ##### Running download-and-extract step. 01:32:38 INFO - ##### 01:32:38 INFO - Running pre-action listener: _resource_record_pre_action 01:32:38 INFO - Running main action method: download_and_extract 01:32:38 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:38 INFO - https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 01:32:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/test_packages.json 01:32:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/test_packages.json 01:32:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:32:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:32:41 INFO - Downloaded 1302 bytes. 01:32:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:32:41 INFO - Using the following test package requirements: 01:32:41 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:32:41 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:32:41 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'jsshell-linux-x86_64.zip'], 01:32:41 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:32:41 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:32:41 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:32:41 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:32:41 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:32:41 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:32:41 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:32:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:32:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:32:41 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')]} 01:32:41 INFO - https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:32:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:32:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:32:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:43 INFO - Downloaded 22525346 bytes. 01:32:43 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 01:32:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:32:43 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 01:32:44 INFO - Return code: 0 01:32:44 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 01:32:44 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')]} 01:32:44 INFO - https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:32:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:32:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:32:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:50 INFO - Downloaded 22525346 bytes. 01:32:50 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 01:32:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:32:50 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 01:32:51 INFO - Return code: 0 01:32:51 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')]} 01:32:51 INFO - https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:32:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:32:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:32:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:52 INFO - Downloaded 11130997 bytes. 01:32:52 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 01:32:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:32:52 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 01:32:53 INFO - Return code: 0 01:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:53 INFO - https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:32:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:32:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:32:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LJzXHVDxTAynr1kwWevkyw/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 01:32:54 INFO - Downloaded 57347992 bytes. 01:32:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:32:54 INFO - mkdir: /builds/slave/test-pgo/properties 01:32:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:32:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:32:54 INFO - Contents: 01:32:54 INFO - build_url:https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:32:54 INFO - Running post-action listener: _resource_record_post_action 01:32:54 INFO - Running post-action listener: set_extra_try_arguments 01:32:54 INFO - ##### 01:32:54 INFO - ##### Running populate-webroot step. 01:32:54 INFO - ##### 01:32:54 INFO - Running pre-action listener: _resource_record_pre_action 01:32:54 INFO - Running main action method: populate_webroot 01:32:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:32:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:32:54 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 01:32:54 INFO - '../fennec_ids.txt'], 01:32:54 INFO - 'tests': ['tcheck2']}, 01:32:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:32:54 INFO - '--tppagecycles', 01:32:54 INFO - '7'], 01:32:54 INFO - 'tests': ['tsvgm']}}, 01:32:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:32:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:32:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:32:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:32:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:32:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:32:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'tests': ['damp', 'tps']}, 01:32:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['damp', 'tps']}, 01:32:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:32:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:32:54 INFO - 'other': {'tests': ['a11yr', 01:32:54 INFO - 'ts_paint', 01:32:54 INFO - 'tpaint', 01:32:54 INFO - 'sessionrestore', 01:32:54 INFO - 'sessionrestore_no_auto_restore']}, 01:32:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['a11yr', 01:32:54 INFO - 'ts_paint', 01:32:54 INFO - 'tpaint', 01:32:54 INFO - 'sessionrestore', 01:32:54 INFO - 'sessionrestore_no_auto_restore']}, 01:32:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['a11yr', 01:32:54 INFO - 'ts_paint', 01:32:54 INFO - 'tpaint', 01:32:54 INFO - 'sessionrestore', 01:32:54 INFO - 'sessionrestore_no_auto_restore']}, 01:32:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['a11yr', 01:32:54 INFO - 'ts_paint', 01:32:54 INFO - 'tpaint', 01:32:54 INFO - 'sessionrestore', 01:32:54 INFO - 'sessionrestore_no_auto_restore']}, 01:32:54 INFO - 'other_l64': {'tests': ['a11yr', 01:32:54 INFO - 'ts_paint', 01:32:54 INFO - 'tpaint', 01:32:54 INFO - 'sessionrestore', 01:32:54 INFO - 'sessionrestore_no_auto_restore']}, 01:32:54 INFO - 'other_nol64': {'tests': ['a11yr', 01:32:54 INFO - 'ts_paint', 01:32:54 INFO - 'tpaint', 01:32:54 INFO - 'sessionrestore', 01:32:54 INFO - 'sessionrestore_no_auto_restore']}, 01:32:54 INFO - 'svgr': {'tests': ['tsvgx', 01:32:54 INFO - 'tsvgr_opacity', 01:32:54 INFO - 'tart', 01:32:54 INFO - 'tscrollx', 01:32:54 INFO - 'cart']}, 01:32:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['tsvgx', 01:32:54 INFO - 'tsvgr_opacity', 01:32:54 INFO - 'tart', 01:32:54 INFO - 'tscrollx', 01:32:54 INFO - 'cart']}, 01:32:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'tests': ['tp5o']}, 01:32:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'talos_options': ['--e10s'], 01:32:54 INFO - 'tests': ['tp5o']}, 01:32:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'talos_options': ['--xperf_path', 01:32:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:32:54 INFO - 'tests': ['tp5n']}, 01:32:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:54 INFO - 'talos_options': ['--e10s', 01:32:54 INFO - '--xperf_path', 01:32:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:32:54 INFO - 'tests': ['tp5n']}}, 01:32:54 INFO - 'talos.zip': {'path': '', 01:32:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:32:54 INFO - Running post-action listener: _resource_record_post_action 01:32:54 INFO - ##### 01:32:54 INFO - ##### Running create-virtualenv step. 01:32:54 INFO - ##### 01:32:54 INFO - Running pre-action listener: _resource_record_pre_action 01:32:54 INFO - Running main action method: create_virtualenv 01:32:54 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:32:54 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 01:32:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:32:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:32:54 INFO - Using real prefix '/usr' 01:32:54 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:32:55 INFO - Installing distribute.............................................................................................................................................................................................done. 01:32:57 INFO - Installing pip.................done. 01:32:57 INFO - Return code: 0 01:32:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:32:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 01:32:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:32:57 INFO - Using env: {'DISPLAY': ':0', 01:32:57 INFO - 'HOME': '/home/cltbld', 01:32:57 INFO - 'LANG': 'en_US.UTF-8', 01:32:57 INFO - 'LANGUAGE': 'en_US:en', 01:32:57 INFO - 'LOGNAME': 'cltbld', 01:32:57 INFO - 'MAIL': '/var/mail/cltbld', 01:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:57 INFO - 'MOZ_NO_REMOTE': '1', 01:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:57 INFO - 'NO_EM_RESTART': '1', 01:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:32:57 INFO - 'PWD': '/builds/slave/test-pgo', 01:32:57 INFO - 'SHELL': '/bin/bash', 01:32:57 INFO - 'SHLVL': '1', 01:32:57 INFO - 'TERM': 'linux', 01:32:57 INFO - 'TMOUT': '86400', 01:32:57 INFO - 'USER': 'cltbld', 01:32:57 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:57 INFO - '_': '/tools/buildbot/bin/python'} 01:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:57 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 01:32:57 INFO - Cleaning up... 01:32:57 INFO - Return code: 0 01:32:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:32:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 01:32:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:32:57 INFO - Using env: {'DISPLAY': ':0', 01:32:57 INFO - 'HOME': '/home/cltbld', 01:32:57 INFO - 'LANG': 'en_US.UTF-8', 01:32:57 INFO - 'LANGUAGE': 'en_US:en', 01:32:57 INFO - 'LOGNAME': 'cltbld', 01:32:57 INFO - 'MAIL': '/var/mail/cltbld', 01:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:57 INFO - 'MOZ_NO_REMOTE': '1', 01:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:57 INFO - 'NO_EM_RESTART': '1', 01:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:32:57 INFO - 'PWD': '/builds/slave/test-pgo', 01:32:57 INFO - 'SHELL': '/bin/bash', 01:32:57 INFO - 'SHLVL': '1', 01:32:57 INFO - 'TERM': 'linux', 01:32:57 INFO - 'TMOUT': '86400', 01:32:57 INFO - 'USER': 'cltbld', 01:32:57 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:57 INFO - '_': '/tools/buildbot/bin/python'} 01:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:57 INFO - Downloading/unpacking psutil>=0.7.1 01:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:59 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 01:32:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 01:32:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:32:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:32:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:32:59 INFO - Installing collected packages: psutil 01:32:59 INFO - Running setup.py install for psutil 01:32:59 INFO - building 'psutil._psutil_linux' extension 01:32:59 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 01:32:59 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 01:32:59 INFO - building 'psutil._psutil_posix' extension 01:32:59 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 01:32:59 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 01:32:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:32:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:32:59 INFO - Successfully installed psutil 01:32:59 INFO - Cleaning up... 01:32:59 INFO - Return code: 0 01:32:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:32: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')]} 01:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32: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')]} 01:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:59 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 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:32:59 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 01:32:59 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 01:32:59 INFO - Using env: {'DISPLAY': ':0', 01:32:59 INFO - 'HOME': '/home/cltbld', 01:32:59 INFO - 'LANG': 'en_US.UTF-8', 01:32:59 INFO - 'LANGUAGE': 'en_US:en', 01:32:59 INFO - 'LOGNAME': 'cltbld', 01:32:59 INFO - 'MAIL': '/var/mail/cltbld', 01:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:59 INFO - 'MOZ_NO_REMOTE': '1', 01:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:59 INFO - 'NO_EM_RESTART': '1', 01:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:32:59 INFO - 'PWD': '/builds/slave/test-pgo', 01:32:59 INFO - 'SHELL': '/bin/bash', 01:32:59 INFO - 'SHLVL': '1', 01:32:59 INFO - 'TERM': 'linux', 01:32:59 INFO - 'TMOUT': '86400', 01:32:59 INFO - 'USER': 'cltbld', 01:32:59 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:59 INFO - '_': '/tools/buildbot/bin/python'} 01:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:33:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 01:33:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:33:01 INFO - Installing collected packages: mozsystemmonitor 01:33:01 INFO - Running setup.py install for mozsystemmonitor 01:33:01 INFO - Successfully installed mozsystemmonitor 01:33:01 INFO - Cleaning up... 01:33:01 INFO - Return code: 0 01:33:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:33: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')]} 01:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33: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')]} 01:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:33:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 01:33:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:33:01 INFO - Using env: {'DISPLAY': ':0', 01:33:01 INFO - 'HOME': '/home/cltbld', 01:33:01 INFO - 'LANG': 'en_US.UTF-8', 01:33:01 INFO - 'LANGUAGE': 'en_US:en', 01:33:01 INFO - 'LOGNAME': 'cltbld', 01:33:01 INFO - 'MAIL': '/var/mail/cltbld', 01:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:01 INFO - 'MOZ_NO_REMOTE': '1', 01:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:01 INFO - 'NO_EM_RESTART': '1', 01:33:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:33:01 INFO - 'PWD': '/builds/slave/test-pgo', 01:33:01 INFO - 'SHELL': '/bin/bash', 01:33:01 INFO - 'SHLVL': '1', 01:33:01 INFO - 'TERM': 'linux', 01:33:01 INFO - 'TMOUT': '86400', 01:33:01 INFO - 'USER': 'cltbld', 01:33:01 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:01 INFO - '_': '/tools/buildbot/bin/python'} 01:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:02 INFO - Downloading/unpacking blobuploader==1.2.4 01:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:03 INFO - Downloading blobuploader-1.2.4.tar.gz 01:33:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 01:33:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:33:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 01:33:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:33:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:33:04 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 01:33:04 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 01:33:04 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 01:33:04 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 01:33:04 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 01:33:04 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 01:33:04 INFO - Downloading docopt-0.6.1.tar.gz 01:33:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 01:33:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:33:04 INFO - Installing collected packages: blobuploader, requests, docopt 01:33:04 INFO - Running setup.py install for blobuploader 01:33:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:33:04 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:33:04 INFO - Running setup.py install for requests 01:33:04 INFO - Running setup.py install for docopt 01:33:05 INFO - Successfully installed blobuploader requests docopt 01:33:05 INFO - Cleaning up... 01:33:05 INFO - Return code: 0 01:33:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:05 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 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:33:05 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 01:33:05 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 01:33:05 INFO - Using env: {'DISPLAY': ':0', 01:33:05 INFO - 'HOME': '/home/cltbld', 01:33:05 INFO - 'LANG': 'en_US.UTF-8', 01:33:05 INFO - 'LANGUAGE': 'en_US:en', 01:33:05 INFO - 'LOGNAME': 'cltbld', 01:33:05 INFO - 'MAIL': '/var/mail/cltbld', 01:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:05 INFO - 'MOZ_NO_REMOTE': '1', 01:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:05 INFO - 'NO_EM_RESTART': '1', 01:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:33:05 INFO - 'PWD': '/builds/slave/test-pgo', 01:33:05 INFO - 'SHELL': '/bin/bash', 01:33:05 INFO - 'SHLVL': '1', 01:33:05 INFO - 'TERM': 'linux', 01:33:05 INFO - 'TMOUT': '86400', 01:33:05 INFO - 'USER': 'cltbld', 01:33:05 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:05 INFO - '_': '/tools/buildbot/bin/python'} 01:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:33:05 INFO - Running setup.py (path:/tmp/pip-xitEcK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:33:05 INFO - Running setup.py (path:/tmp/pip-PRymGd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:33:05 INFO - Running setup.py (path:/tmp/pip-PWEAq1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:33:05 INFO - Running setup.py (path:/tmp/pip-wVcFJ_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:33:05 INFO - Running setup.py (path:/tmp/pip-noUtf9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:33:05 INFO - Running setup.py (path:/tmp/pip-beXznE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:33:05 INFO - Running setup.py (path:/tmp/pip-zy5UYK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:33:05 INFO - Running setup.py (path:/tmp/pip-ARGlrz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:33:05 INFO - Running setup.py (path:/tmp/pip-RiRGPl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:33:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:33:05 INFO - Running setup.py (path:/tmp/pip-vccIcR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:33:06 INFO - Running setup.py (path:/tmp/pip-Ue46iG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:33:06 INFO - Running setup.py (path:/tmp/pip-W7QJA7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:33:06 INFO - Running setup.py (path:/tmp/pip-vFqd0f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:33:06 INFO - Running setup.py (path:/tmp/pip-Zixb6B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:33:06 INFO - Running setup.py (path:/tmp/pip-br3a8J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:33:06 INFO - Running setup.py (path:/tmp/pip-FuaIXq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:33:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:33:06 INFO - Running setup.py (path:/tmp/pip-vEWRaF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:33:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:33:06 INFO - Running setup.py install for manifestparser 01:33:06 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:33:06 INFO - Running setup.py install for mozcrash 01:33:06 INFO - Running setup.py install for mozdebug 01:33:06 INFO - Running setup.py install for mozdevice 01:33:06 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:33:06 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:33:06 INFO - Running setup.py install for mozfile 01:33:07 INFO - Running setup.py install for mozhttpd 01:33:07 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for mozinfo 01:33:07 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for mozInstall 01:33:07 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for mozleak 01:33:07 INFO - Running setup.py install for mozlog 01:33:07 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for moznetwork 01:33:07 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for mozprocess 01:33:07 INFO - Running setup.py install for mozprofile 01:33:07 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for mozrunner 01:33:07 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:33:07 INFO - Running setup.py install for mozscreenshot 01:33:08 INFO - Running setup.py install for moztest 01:33:08 INFO - Running setup.py install for mozversion 01:33:08 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:33:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:33:08 INFO - Cleaning up... 01:33:08 INFO - Return code: 0 01:33:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:33:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 01:33:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/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 01:33:08 INFO - Using env: {'DISPLAY': ':0', 01:33:08 INFO - 'HOME': '/home/cltbld', 01:33:08 INFO - 'LANG': 'en_US.UTF-8', 01:33:08 INFO - 'LANGUAGE': 'en_US:en', 01:33:08 INFO - 'LOGNAME': 'cltbld', 01:33:08 INFO - 'MAIL': '/var/mail/cltbld', 01:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:08 INFO - 'MOZ_NO_REMOTE': '1', 01:33:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:08 INFO - 'NO_EM_RESTART': '1', 01:33:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:33:08 INFO - 'PWD': '/builds/slave/test-pgo', 01:33:08 INFO - 'SHELL': '/bin/bash', 01:33:08 INFO - 'SHLVL': '1', 01:33:08 INFO - 'TERM': 'linux', 01:33:08 INFO - 'TMOUT': '86400', 01:33:08 INFO - 'USER': 'cltbld', 01:33:08 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:08 INFO - '_': '/tools/buildbot/bin/python'} 01:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:33:08 INFO - Running setup.py (path:/tmp/pip-8f0QsE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:33:08 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)) 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:33:08 INFO - Running setup.py (path:/tmp/pip-0ynXIE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:33:08 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)) 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:33:08 INFO - Running setup.py (path:/tmp/pip-NqBFgc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:33:08 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)) 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:33:08 INFO - Running setup.py (path:/tmp/pip-G6CW8W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:33:08 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)) 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:33:08 INFO - Running setup.py (path:/tmp/pip-ri9If1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:33:08 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)) 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:33:08 INFO - Running setup.py (path:/tmp/pip-TjuUeE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:33:08 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)) 01:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:33:08 INFO - Running setup.py (path:/tmp/pip-5Hp__Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:33:09 INFO - Running setup.py (path:/tmp/pip-AgOIhe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:33:09 INFO - Running setup.py (path:/tmp/pip-QGibZr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:33:09 INFO - Running setup.py (path:/tmp/pip-O4q9jx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:33:09 INFO - Running setup.py (path:/tmp/pip-DVfOZk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:33:09 INFO - Running setup.py (path:/tmp/pip-BcFmOb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:33:09 INFO - Running setup.py (path:/tmp/pip-z4wltK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:33:09 INFO - Running setup.py (path:/tmp/pip-wmLkRW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:33:09 INFO - Running setup.py (path:/tmp/pip-9vE_kL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:33:09 INFO - Running setup.py (path:/tmp/pip-ruYvMn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:33:09 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)) 01:33:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:33:09 INFO - Running setup.py (path:/tmp/pip-VeomhJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:33:09 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)) 01:33:09 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)) 01:33:09 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)) 01:33:09 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)) 01:33:09 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)) 01:33:09 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)) 01:33:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:33:09 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 01:33:09 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 01:33:09 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 01:33:09 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 01:33:09 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 01:33:09 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 01:33:11 INFO - Downloading blessings-1.5.1.tar.gz 01:33:11 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 01:33:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:33:11 INFO - Installing collected packages: blessings 01:33:11 INFO - Running setup.py install for blessings 01:33:11 INFO - Successfully installed blessings 01:33:11 INFO - Cleaning up... 01:33:11 INFO - Return code: 0 01:33:11 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:33:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:33:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:33:11 INFO - Reading from file tmpfile_stdout 01:33:11 INFO - Current package versions: 01:33:11 INFO - argparse == 1.2.1 01:33:11 INFO - blessings == 1.5.1 01:33:11 INFO - blobuploader == 1.2.4 01:33:11 INFO - docopt == 0.6.1 01:33:11 INFO - manifestparser == 1.1 01:33:11 INFO - mozInstall == 1.12 01:33:11 INFO - mozcrash == 0.16 01:33:11 INFO - mozdebug == 0.1 01:33:11 INFO - mozdevice == 0.47 01:33:11 INFO - mozfile == 1.2 01:33:11 INFO - mozhttpd == 0.7 01:33:11 INFO - mozinfo == 0.9 01:33:11 INFO - mozleak == 0.1 01:33:11 INFO - mozlog == 3.0 01:33:11 INFO - moznetwork == 0.27 01:33:11 INFO - mozprocess == 0.22 01:33:11 INFO - mozprofile == 0.27 01:33:11 INFO - mozrunner == 6.11 01:33:11 INFO - mozscreenshot == 0.1 01:33:11 INFO - mozsystemmonitor == 0.0 01:33:11 INFO - moztest == 0.7 01:33:11 INFO - mozversion == 1.4 01:33:11 INFO - psutil == 3.1.1 01:33:11 INFO - requests == 1.2.3 01:33:11 INFO - wsgiref == 0.1.2 01:33:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:33:11 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')]} 01:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:11 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')]} 01:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:11 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 0x7f423f77cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28886c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293a7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, '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': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', '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 01:33:11 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 01:33:11 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 01:33:11 INFO - Using env: {'DISPLAY': ':0', 01:33:11 INFO - 'HOME': '/home/cltbld', 01:33:11 INFO - 'LANG': 'en_US.UTF-8', 01:33:11 INFO - 'LANGUAGE': 'en_US:en', 01:33:11 INFO - 'LOGNAME': 'cltbld', 01:33:11 INFO - 'MAIL': '/var/mail/cltbld', 01:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:11 INFO - 'MOZ_NO_REMOTE': '1', 01:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:11 INFO - 'NO_EM_RESTART': '1', 01:33:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:33:11 INFO - 'PWD': '/builds/slave/test-pgo', 01:33:11 INFO - 'SHELL': '/bin/bash', 01:33:11 INFO - 'SHLVL': '1', 01:33:11 INFO - 'TERM': 'linux', 01:33:11 INFO - 'TMOUT': '86400', 01:33:11 INFO - 'USER': 'cltbld', 01:33:11 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:11 INFO - '_': '/tools/buildbot/bin/python'} 01:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 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)) 01:33:12 INFO - Cleaning up... 01:33:12 INFO - Return code: 0 01:33:12 INFO - Running post-action listener: _resource_record_post_action 01:33:12 INFO - Running post-action listener: _start_resource_monitoring 01:33:12 INFO - Starting resource monitoring. 01:33:12 INFO - ##### 01:33:12 INFO - ##### Running install step. 01:33:12 INFO - ##### 01:33:12 INFO - Running pre-action listener: _resource_record_pre_action 01:33:12 INFO - Running main action method: install 01:33:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:33:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:33:12 INFO - Reading from file tmpfile_stdout 01:33:12 INFO - Detecting whether we're running mozinstall >=1.0... 01:33:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:33:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:33:12 INFO - Reading from file tmpfile_stdout 01:33:12 INFO - Output received: 01:33:12 INFO - Usage: mozinstall [options] installer 01:33:12 INFO - Options: 01:33:12 INFO - -h, --help show this help message and exit 01:33:12 INFO - -d DEST, --destination=DEST 01:33:12 INFO - Directory to install application into. [default: 01:33:12 INFO - "/builds/slave/test-pgo"] 01:33:12 INFO - --app=APP Application being installed. [default: firefox] 01:33:12 INFO - mkdir: /builds/slave/test-pgo/build/application 01:33:12 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'] 01:33:12 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 01:33:26 INFO - Reading from file tmpfile_stdout 01:33:26 INFO - Output received: 01:33:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:33:26 INFO - Running post-action listener: _resource_record_post_action 01:33:26 INFO - ##### 01:33:26 INFO - ##### Running run-tests step. 01:33:26 INFO - ##### 01:33:26 INFO - Running pre-action listener: _resource_record_pre_action 01:33:26 INFO - Running main action method: run_tests 01:33:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:33:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:33:26 INFO - Python 2.7.3 01:33:26 INFO - Return code: 0 01:33:26 INFO - grabbing minidump binary from tooltool 01:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:33:26 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 0x297ed70>, '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 0x297fb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297ffb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:33:26 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 01:33:26 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 01:33:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:33:26 INFO - Return code: 0 01:33:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:33:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:33:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:33:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:33:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:33:26 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/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 01:33:26 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/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost 01:33:26 INFO - Using env: {'DISPLAY': ':0', 01:33:26 INFO - 'HOME': '/home/cltbld', 01:33:26 INFO - 'LANG': 'en_US.UTF-8', 01:33:26 INFO - 'LANGUAGE': 'en_US:en', 01:33:26 INFO - 'LOGNAME': 'cltbld', 01:33:26 INFO - 'MAIL': '/var/mail/cltbld', 01:33:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:33:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 01:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:26 INFO - 'MOZ_NO_REMOTE': '1', 01:33:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:33:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:26 INFO - 'NO_EM_RESTART': '1', 01:33:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:33:26 INFO - 'PWD': '/builds/slave/test-pgo', 01:33:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 01:33:26 INFO - 'SHELL': '/bin/bash', 01:33:26 INFO - 'SHLVL': '1', 01:33:26 INFO - 'TERM': 'linux', 01:33:26 INFO - 'TMOUT': '86400', 01:33:26 INFO - 'USER': 'cltbld', 01:33:26 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910', 01:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:26 INFO - '_': '/tools/buildbot/bin/python'} 01:33:26 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/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] with output_timeout 3600 01:33:27 INFO - mozversion INFO | application_buildid: 20151119233015 01:33:27 INFO - mozversion INFO | application_changeset: 1b2e15608f34fef0f23369731c80138f55f00cf2 01:33:27 INFO - mozversion INFO | application_display_name: Nightly 01:33:27 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:33:27 INFO - mozversion INFO | application_name: Firefox 01:33:27 INFO - mozversion INFO | application_remotingname: firefox 01:33:27 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 01:33:27 INFO - mozversion INFO | application_vendor: Mozilla 01:33:27 INFO - mozversion INFO | application_version: 45.0a1 01:33:27 INFO - mozversion INFO | platform_buildid: 20151119233015 01:33:27 INFO - mozversion INFO | platform_changeset: 1b2e15608f34fef0f23369731c80138f55f00cf2 01:33:27 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 01:33:27 INFO - mozversion INFO | platform_version: 45.0a1 01:33:27 INFO - 2015-11-20 01:33:27,034 DEBUG : using testdate: 1448012007 01:33:27 INFO - 2015-11-20 01:33:27,034 DEBUG : actual date: 1448012007 01:33:27 INFO - 2015-11-20 01:33:27,082 INFO : starting webserver on 'localhost:42604' 01:33:27 INFO - 2015-11-20 01:33:27,082 INFO : Starting test suite talos-linux64-ix-008 01:33:27 INFO - 2015-11-20 01:33:27,082 INFO : Starting test dromaeo_css 01:33:27 INFO - 2015-11-20 01:33:27,083 DEBUG : operating with platform_type : linux_ 01:33:27 INFO - 2015-11-20 01:33:27,083 INFO : Initialising browser for dromaeo_css test... 01:33:27 INFO - 2015-11-20 01:33:27,090 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppCeWSl/profile http://localhost:42604/getInfo.html 01:33:32 INFO - 2015-11-20 01:33:32,332 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:33:32 INFO - 2015-11-20 01:33:32,332 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:33:32 INFO - 2015-11-20 01:33:32,332 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:33:32 INFO - 2015-11-20 01:33:32,332 DEBUG : BROWSER_OUTPUT: __metrics 01:33:32 INFO - 2015-11-20 01:33:32,414 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:33:33 INFO - 2015-11-20 01:33:33,760 INFO : Browser initialized. 01:33:33 INFO - 2015-11-20 01:33:33,760 INFO : Running cycle 1/1 for dromaeo_css test... 01:33:33 INFO - 2015-11-20 01:33:33,761 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppCeWSl/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:33:45 INFO - 2015-11-20 01:33:45,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 141811712 01:33:45 INFO - 2015-11-20 01:33:45,536 DEBUG : BROWSER_OUTPUT: 01:34:37 INFO - 2015-11-20 01:34:37,070 DEBUG : BROWSER_OUTPUT: 1448012077069 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] 01:36:45 INFO - 2015-11-20 01:36:45,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42604/tests/dromaeo/cssquery-ext.html) 01:36:46 INFO - 2015-11-20 01:36:46,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 267296768 01:36:46 INFO - 2015-11-20 01:36:46,859 DEBUG : BROWSER_OUTPUT: 01:39:49 INFO - 2015-11-20 01:39:49,346 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/dromaeo/cssquery-ext.html (next: http://localhost:42604/tests/dromaeo/cssquery-jquery.html) 01:39:49 INFO - 2015-11-20 01:39:49,913 DEBUG : BROWSER_OUTPUT: RSS: Main: 226934784 01:39:49 INFO - 2015-11-20 01:39:49,913 DEBUG : BROWSER_OUTPUT: 01:42:51 INFO - 2015-11-20 01:42:51,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42604/tests/dromaeo/cssquery-mootools.html) 01:42:52 INFO - 2015-11-20 01:42:52,349 DEBUG : BROWSER_OUTPUT: RSS: Main: 226004992 01:42:52 INFO - 2015-11-20 01:42:52,350 DEBUG : BROWSER_OUTPUT: 01:45:54 INFO - 2015-11-20 01:45:54,145 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42604/tests/dromaeo/cssquery-prototype.html) 01:45:54 INFO - 2015-11-20 01:45:54,711 DEBUG : BROWSER_OUTPUT: RSS: Main: 204320768 01:45:54 INFO - 2015-11-20 01:45:54,712 DEBUG : BROWSER_OUTPUT: 01:48:57 INFO - 2015-11-20 01:48:57,219 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42604/tests/dromaeo/cssquery-yui.html) 01:48:57 INFO - 2015-11-20 01:48:57,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 205230080 01:48:57 INFO - 2015-11-20 01:48:57,801 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/dromaeo/cssquery-yui.html (next: http://localhost:42604/tests/dromaeo/cssquery-dojo.html) 01:52:00 INFO - 2015-11-20 01:52:00,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 280162304 01:52:00 INFO - 2015-11-20 01:52:00,736 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,737 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:52:00 INFO - 2015-11-20 01:52:00,738 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:52:00 INFO - 2015-11-20 01:52:00,738 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:52:00 INFO - 2015-11-20 01:52:00,738 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:52:00 INFO - 2015-11-20 01:52:00,739 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3189.856456459459;3253.5119732040175;3293.8188399638025;3298.356820420016;3317.2702005558785;5187.040397526451;5285.973570132153;5387.568994480442;5397.136458166694;5423.294971653684;5396.406395296514;5401.78392864285;5462.126059830428;5467.097928841737;5521;4942.999999999991;4977.303177555145;5002.474740152286;5024.221245706921;5042.067217564762;4845.491223421525;5037.823676171335;5052.646314757969;5077.517635824609;5122;4939.753012349368;4955.380577427822;4975.378077740283;4984.601231901475;5028.019536190444;4935.333729946447;4940;4951.752412379403;4960.032793605248;5005.34930459038;4824.5899098576665;4929.383827021622;4929.457759646436;4933.1860243059855;4943.678660887031;5189.481051894818;5251.186066159751;5288.9735551322;5313.495217954288;5334.67991920486;5904.911426328608;5908.02517584598;5909.349971503131;5910.586258961875;5923.733431995571;5792.478676919098;5925.4370834770625;5934.050551911717;5941.940580594225;5947.345791962881;5352.090144675414;5452.427495113036;5494.5054945054735;5528.502434780889;5533.806316778893;5957.076653118774;5958.791442299499;5975.970120149372;5978.252718410199;6039;5532.529734972572;5768.711564421762;5777.19119323295;5782.392848750905;5800.767969281266;5772.797952071742;5877.882442351129;5889.705514724246;5892.381299963527;5896.469317761422;5860.267466566679;5903.203067585821;5907.1139329100115;5911.527077833763;5914.201582786355;5899.852503687356;5906.202662640574;5906.970465147647;5910.379410161871;5917.881642367214;4417.580329868657;4490.753008584494;4555.794989225493;4561.680682352204;4578.862634120915;4051.7974101294817;4072.3280658690983;4076.449679293317;4084.6528045116497;4115.300398932189;5588.720563971785;5608.046632072557;5610.551155907518;5612.410696876769;5659.801906933238;5691.943080569225;5772.163036359789;5810.477057064884;5823.039198532279;5891.852703682443;5550.6669599823335;5614.129809879475;5615.775368985276;5616.5787565932715;5616.859578510488;5186.118359878829;5187.688738675692;5196.662216955886;5200.427952925175;5251.868703282372;5831.650100993954;5940.821775346746;5945.672987985702;6000.609960352563;6127.29536103336;5931.228940237742;5936.406359364029;5938.168656388023;5946.226990491208;5960.672163031075;6115.266168059861;6122.418370254819;6122.448979591858;6129.172561704113;6133.539984501091;5681.034224181982;5710.114932185518;5721.971390143189;5773.797917072882;5827.184194212899;5897.3807750185615;5904.763809447574;5943.554233432424;5945.732442040205;5952.285725712942;5602.803901863415;5812.273465816773;5822.70886455684;5822.796202132905;5824.301083869963;5702.914456283076;5706.629069110494;5731.168980497888;5800.883982320414;5845.18167456565;5610.382857885547;5610.607257491936;5611.747471363717;5613.635113717595;5614.242077319673;5522.917156242579;5727.31272247333;5767.221425107725;5770.769169233184;5790.681512516852;5602.747876345493;5617.747201375866;5693.459121383462;5710.172025056427;5750.827475175752 01:52:00 INFO - 2015-11-20 01:52:00,743 DEBUG : BROWSER_OUTPUT: |1;ext.html;572.1989215098864;582.263436752508;588.1325045557803;589.089856172214;597.7220592424527;31160.688393116063;38761;39235.01912452187;39324.60675393245;39349.60650393495;41089.58910410895;41335.58664413355;41631.583684163146;41661;41676.37485437721;7538.1331146918255;8694.304455643549;8876.556172191396;8879.245264152523;8899.73300800977;7624.046994125734;10551.894481055206;10793.2984356017;10815.297005694605;10819.026287634111;10447.895521044768;10466.58133674656;10508.842367364496;10545.894541054568;10567.947160264226;7706.7687969361;7874.881876771853;10416.27086103973;10498.790024199512;10578.841317380246;10256.897431025669;10378.325408848401;10379.948100259526;10386.532606032746;10392.116670082976;39718;40120;40205.19589608192;40225.396619050734;40239;3168.556402103707;3206.887758928426;3220.6940340667597;3599.748017638766;3739.719521035933;29560.556591651144;29643.407131857242;29647.851760741272;29912.70087299121;30528.84735576308;3946.565877753445;3993.0815912340327;4002.519697636273;4005.178938317638;4095.4061661059277;267992;268368;268644;269289;271000;55461.44538554644;56471.43528564662;59493.70253148793;59822.70088649529;60623;56528.15207771887;56580;56772.432275677544;56911.71544142336;56998;15120.412356702607;15367.385304587915;15434;15439.915611392318;15440.382384704486;11274.436278186222;11327.376994265394;11347.319160850375;11348.205625606128;11351.489182986785;19839.30562430308;20155.39533813988;20158.69761953572;20198.39404817858;20222.988850557413;7907.367410607138;8218.301444377295;8219.095899451055;8228.50628962264;8253.793655158548;918.4397517514311;920.9171174594319;1016.2936758952457;1031.097789434245;1055.5935964653663;28116.71883281182;28351.640735992172;28476.145715628565;28635.57046644302;28659.713402865706;1024.416082832793;1039.3036665434176;1040.2250323509033;1044.2742294105676;1146.8853114688463;14742.705145897022;14803.111813291236;14914.254287285592;14924.179170145746;14935.626609334853;396011;400691;401478;402016;402399;54232.45767542274;54732;54792.45207547953;54851.72574137104;55002.44997550053;1307.4900788692419;1314.4610709609206;1330.0490149543123;1336.1982810313928;1338.765715999696;27258.591121132802;27908.441831162858;28203.091647389083;28329.150125496268;28440.57339139956;43344.78327608468;43935;43963.34054989242;43999;44010.33984490299;289125;290642;291169;291828;291885;27703.72296277092;31129.221769455038;31237.531437028007;31375.686243137276;31678.524822127227;40222.1955560893;40921.59078409178;41269.58730412657;41793;41970.58029419789;35068;35179.64820351764;35314.64685353216;40868;41582.1683566333;32467.675323246465;38061.80969095137;38722.22555548817;40556.59443405528;41079.58920410758;37834.81082594569;38157.61842381541;38973.61026389813;39006.80496597499;39150;36607.45088823616;38154.236915262096;38799.6120038796;39273.410898835966;39763.60236397599;27102.186934392;29063;29646;29705.405891882474;29712.257193570334 01:52:00 INFO - 2015-11-20 01:52:00,743 DEBUG : BROWSER_OUTPUT: |2;jquery.html;339.3603058235226;339.50601874272934;340.69337596163433;341.9247765491591;343.75593328736596;14616;15060.24698765063;16192.757108643365;16615.916920415395;18679.71980420295;15138.318775655094;15217.086974781521;15729.05625662461;15759.763603545927;15834.129122898235;5016.322796422482;5333.359996800389;5338.225957236198;5339.225812257239;5345.812896548621;3870.5161854768153;3960.8415663373576;5300.840974770763;5700;5867.295924489067;5781.595288329819;5789.594728369016;5792.7972520961775;5811.0702287633985;5822.3886491918165;5724.656520608777;5854.268216472941;5885.61743486672;5892.882142357172;5894.675792831393;5088.931324421839;5124.436312005713;5128.230765385222;5149.716765577892;5156.458571849939;11384.260023098555;11556.364399957998;19342.226310947546;19798.80201197984;19811.405657830288;2106.65240235361;2132.2813412898677;2134.6584546472645;2170.6526955687013;2390.032037025008;8710.346723995663;8770.912290877073;12058.15592908488;12687.492500299977;12694.174878632859;1676.4383931383002;1698.4904528641482;1791.9104044797707;1814.2289526951124;1869.5419622192514;58415.70792146055;58536.12195817066;59870;60305;61256.081158782654;9807;10176.236782241362;10183;10188.541515631814;10191.133753631024;13283.933580332037;13297.800532992012;13305.600831975056;13325.000624953167;13330.866691332962;6937;7178.066851309401;7320.194778574352;7375.963120184365;7548.056492938383;6986.16166060076;7001.824954376181;7006.614636195059;7020.438364930793;7046.859062818715;4096.09885825124;4110.403991421287;4128.8852009957145;4188.560201178893;4398.296272596369;1695.5252529291572;1736.3054778088865;1754.6666133434608;1764.4971183212979;1823.972640410395;897.9551022448851;898.8876390451194;942.072059021863;948.6395169835419;980.9068138526828;15715.921420393053;15752.212389380486;16017.759733604005;16288.592785180464;16366.672666546603;906.7733066733317;974.668612671695;996.7906739584769;1022.0188618925914;1052.084686322904;9516.38143520669;9655;9896.25778066648;10021.949890250648;10057.899421005697;565122;567862;571322;577458;588175;73988.89016664754;74408;74747.62626186834;75067.62466187634;76831.2316876824;1151.228676786555;1153.2561497834017;1201.1531870031606;1218.939053047344;1237.2823762218072;14869.256537173314;15095.207386633068;15332.693346133237;15371.538853834403;15590.142542159836;32512.34975300528;33072.834635826664;33383;33869.491957620165;34685;44294.557054430334;44887.32669010033;46398.53601463942;47731;51293.7435312821;8741.169588889044;9141.222996045411;9166.450012999241;9187.816243675223;9206.493642849708;10651.254412191072;10749.838752418877;13575.79636305476;16412.507624771275;16922.915385422995;10378.532966016699;11663;13787;14336.49822256216;14436.061655992327;13412.463501459832;13726.794098088736;13758.65603359924;13956.162630242217;14262.643433914234;13819.170849749047;14005.809136914037;14249.715005700034;14284;14351;8980.281577473917;9021.503817290111;9251.76870578241;9845.60617575289;9930.900690992998;14568.854311457173;14660.266986650839;14739.92630036843;15076.095434273979;15122.697546049238 01:52:00 INFO - 2015-11-20 01:52:00,747 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1277.6039427777384;1642.622196894714;1681.756145358923;1721.8880772749774;1726.2577091850499;6390.297067322591;6984.196817366004;7142.678579463924;7178;7188.209296977335;7235.204127545966;7341.779746607597;7364.705411783522;7395.075615548057;7399.223081576438;2238.0488292475675;2255.436140964759;2258.706368172135;2260.1750361118156;2262.3552287598036;2249.876256805875;2275.24916777463;2282.3609389371004;2286.1884031168943;2288.3592594073684;2194.5281764420647;2208.2271205078255;2211.5355775287208;2218.9334319970344;2245.719285089364;2186.650135978236;2205.228170140454;2210.9447263818374;2212.0156530344;2213.701150344707;2249.3139592424304;2253.177590179589;2257.57106149831;2258.8757618330983;2259.4803195265017;6395.584287021329;6584.078229047937;7316.341529262392;7337.156227033906;7337.74317898871;4381.036172042146;4481.193385190664;4493.752843593601;4513.864584062483;4521.095780843844;3084.6761090085442;3133.075742655922;3141.7643676724338;3161.9525707114412;3287.7369810415107;1010.6765834932825;1012.1447376966452;1045.3518818332682;1051.3008849115329;1067.8505009298703;10002.949985250027;10124.29129960903;10128.949355253177;10134.797304053951;10163.59345626174;4027.1341661542324;4083.734557253769;4126.566710495415;4147.419361289422;4182.121754431542;8373.623186956602;8410.285125764256;8413.537255450887;8426.662933482592;8440.324774018063;4579.473360563511;4579.885502862455;4581.381513495636;4581.977090114595;4582.037772067836;4177.770222637723;4219.51475580306;4221.810018549173;4225.260579398593;4226.57734226581;3274.279658475132;3354.4968254761493;3373.072405088571;3373.6120346160014;3383.154211447138;1022.3712416863575;1036.652721338345;1040.604570263295;1041.9739506512399;1045.4093437207953;1294.249335385474;1314.7436249931172;1336.8195293635429;1340.8390993080698;1346.9964876167123;6889.724411023603;7254.746083887038;7258.0927384076995;7260.165081015751;7267.818304542428;468.3793972985807;475.9857204283877;478.4330568276603;479.2547588499846;481.2876942125674;5225.608079394061;5701.885962280731;5758.193852860602;5759.85600359986;5760.36635970043;8148.511089334659;8334.791630209173;9001.18989290967;9107.772305692279;9136.543172841464;8744.387892847566;8980.640774368952;8983.595738191798;8986.86519702205;9018.639254429749;6755.932440675629;6760.7295708172105;6794.048833163361;6805;6861.073755043005;6998.2651821558;7053.753118640823;7060.046893669289;7081.291870812877;7089.291070892869;7846.33306168982;7942.483738556975;8100.918990810016;8119.878201826859;8145.348372130334;8020.117787043304;8163.591820409075;8164.7550573482895;8327.541985190872;8334.916650833415;6494.480441564758;6574.4411725002;6575.178102737158;6581.6709164540625;6584.275729669828;7371.889421658787;7399.778006659809;7405.370543503863;7412.851742965218;7421.962890185515;6756.121704178622;6934.826629334105;6936.098307220231;6988.860222795617;7069.71721131149;6769.796906092825;6852.828679283058;6883.105196324646;6907.412869905913;6989.545679530815;6890.827729306808;6920.653967301715;6983;6989.860202796017;6996.895046574407;6323.9683801580695;6354.078658594571;6372.904406433814;6381.3937675920715;6390.360963903572;6317.241930968129;6488.513361497812;6527.281998980204;6565.244996825426;6566.6388348639375 01:52:00 INFO - 2015-11-20 01:52:00,747 DEBUG : BROWSER_OUTPUT: |4;prototype.html;322.36976710530917;327.8033180091945;328.19755697320045;328.6155198417851;331.26789794554065;5946.405359464057;6001.099835024743;6010.6994650267425;6021.789237376693;6072.392760723926;5882.235309409782;5890.293164820217;5896.086106653475;5932.288125424944;5939.52483801296;4538.591526762591;4543.90912181757;4547.18150732868;4571.977140114294;4577.176108300505;4484.215262329096;4547.408836851205;4575.428071491064;4578.702384345023;4588.678792484528;4501.099780043988;4511.368408422823;4513.300438432048;4528.932066019012;4533.795979180928;4513.571210734975;4516.1193567254395;4523.389342438778;4527.818887244539;4534.659900507459;3847.826847791828;3928.978465598936;3929.6266854649048;3933.311670457687;3941.763494190329;5592.356878958891;5662.37713851476;5697.287839020122;5699.202111704365;5735.655860648333;5257.106291930381;5259.421463638997;5268.209768534713;5278.076336641073;5278.47215278473;6028.457438830483;6113.388661133851;6137.324894261627;6253.062040693932;6348.968255158741;4391.758453285068;4409.360642706822;4413.029133590605;4416.911661766747;4420.734755914623;6481.675916204171;6558.081868538408;6560.737570497175;6572.737090516373;6577.967110164419;4408.515063343029;4500.122476117192;4509.075639493896;4514.796834142471;4520.52534483881;4561.589456948825;4574;4574.970631607928;4576.290674945389;4577.954220457819;5259.710715910662;5273.209018647233;5273.604479664041;5274.287971123926;5282.392524859613;6368.363163683687;6370.649614271259;6380.936190638127;6383.202099737533;6384.744610215632;1576.4797616786434;1618.9514314570347;1622.1564786311053;1629.8125715542628;1660.418853401324;1088.2817340555355;1090.4438736244413;1092.6612750047511;1096.8628921384827;1103.227740581596;6037.637741735422;6063.060225665029;6064.75740970365;6071.119687645267;6072.666003369857;6490.610563366213;6490.967545162244;6492.870142597121;6496.123023391876;6498.480121590262;6058.091286307001;6061.636301821904;6062.42406971337;6066.090086486974;6069.089636554552;3477.756557040983;3559.039059453933;3566;3566.892993210156;3567.3935430976794;6806.319368063154;6807.149106361705;6817.829554261084;6821.658917054127;6821.69302381394;6726.7981960541265;6733.865322693617;6823.692933817891;6824.0446337512785;6826.93173068263;6583;6713.966430167916;6717.764878229336;6881.65591720412;6901.654917254117;6508.576942498722;6612.371824676648;6641;6647.767328143492;6648.767293144717;6515.967420162869;6541.542092053511;6548.8362790930605;6551.115599394021;6560.737570497126;6733.293004234681;6872.209695885037;6873.312668733087;6874;6881.690323935335;4392.472903251631;4421.734695918255;4424.469063712375;4424.823007079681;4428.867133985986;6609.834754130993;6611.603303801788;6618.2719900811835;6620;6626.900596491153;6297.307296197323;6423.357664233539;6437;6442.71145770857;6444.13004244421;6377.489800816016;6391.616503009835;6393.392627700361;6393.584417012879;6394.840128996626;6189.319174890668;6205.47253483459;6220.782272620618;6227.408396202353;6229.283632382334;6305.779297724557;6435.388638079375;6436.259830119596;6438.935610644021;6458.515611329263;6282.591631543935;6302.495800335871;6303.243610766737;6308.495320374451;6310.5267104968225 01:52:00 INFO - 2015-11-20 01:52:00,750 DEBUG : BROWSER_OUTPUT: |5;yui.html;749.1197842535022;819.6352623082729;834.1033389106708;838.9370797190209;866.900306464756;12736.344275244217;12961.157524760883;13010.479580816767;13011.219326840372;13011.73976520469;12117.575884844033;12194.81707774384;12199.939000305008;12204.572839950604;12205.328706921116;669.7655820462836;683.0846665468272;812.5490352854164;822.9300509456704;827.6482494939656;811.0915774332726;813.1868131868132;813.2843098073687;813.6663967773214;815.0993152566417;707.4941416886925;853.9530325832077;854.0647990450478;856.4390324337569;856.4732689396008;715.3418854653705;851.0213254757017;868.2880038368477;869.1656010230187;870.2646263906988;628.7296462521114;630.8076036808772;632.0172132334219;632.5572099530301;632.7690393006519;12284.938575307156;12320.5071797128;12331.691707707289;12361.134720569564;12416.565420210249;11957.940210298893;12229.449674764657;12315.93842030793;12562.748745025048;12576.182548134342;9855.162311203556;9924.652637157666;10200.744981375525;10462.843057354146;10755.516001779939;3508.5438892943753;3553.07620018797;3608.548931383577;3635.618260082706;3696.907577310562;47760;47823.282650760266;47884;48055.51944480545;49294.50705492935;9041.593128309241;9067.909320906838;9101.40840845343;9257.768555786024;9360.238729574792;17997;18020.819791801914;18044.639107218045;18210.908945455187;18272.360467383845;7696.268854458817;7827.804304892309;7866.370690344758;7873.212678732196;7928.326092282221;4345.391645169679;4346.782660866944;4364.236258654722;4414.139242847678;4429.512753597101;2996.161074899032;3026.7881248312406;3035.089473158044;3043.269615292314;3045.771567132474;561.1834780394598;565.8968029798684;566.1620801214226;570.597728601335;571.5713215088683;614.620177701064;628.9182406287154;635.1774452084469;644.3138057968249;727.7380143148463;8211.384146189059;8289.958550207331;8314;8346.4157508975;8367.414281000272;477.8661974647104;619.8264485943943;653.1345966594187;674.6694119881223;680.9285025072395;4062.1672557125717;4138.317177665712;4150.190712811033;4217.304144816071;4339.696221264481;55965.44034559602;56868.14697779537;57226;57523;57954.42045579575;49960.25059624109;51803.74098129485;51822.22266666003;52042.73978630082;52184.47815521796;45021.77489112599;45114.77442612766;45240;46094.53905460903;46667;8232.382571307056;8734.868976965477;8805.69180078694;8895.555222238732;9100.453972761656;32173.839130804197;32308.19229519281;33062.83468582642;33746.66253337435;34133.146671333416;43856.56143438652;44161.779191105124;44403;44637.33044004277;45477.77261113673;10903.34579925207;10915.836262456229;11006.724831879266;11070.391128487689;11137.832932505857;12383.380830958597;12516.12387132892;12545.310007949285;12659.131823388889;12786.232826030468;10918.454077296263;10978.231523793258;11233.269837460539;11237.943810280896;11597.76804463923;11041.282316649393;11203.375510551088;11374.374409407232;11761.000314973324;11770;10823.296485728402;11171.441427928408;11172.497237624164;11295.7740845181;11514.136439766884;9463.242940564876;9750.463724495221;9843.409395436296;10018;10395.636152734618;9200.815983680423;9248.861267081134;9313.627454901729;9330.440173589335;9462.858057129286 01:52:00 INFO - 2015-11-20 01:52:00,750 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:52:00 INFO - 2015-11-20 01:52:00,750 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:52:00 INFO - 2015-11-20 01:52:00,750 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,959 01:52:00 INFO - 2015-11-20 01:52:00,750 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: __startTimestamp1448013120736__endTimestamp 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: Number of tests: 6 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5453.05 Median:5616.86 stddev:604.89 (10.8%) stddev-sans-first:580.10 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: Values: 3189.9 3253.5 3293.8 3298.4 3317.3 5187.0 5286.0 5387.6 5397.1 5423.3 5396.4 5401.8 5462.1 5467.1 5521.0 4943.0 4977.3 5002.5 5024.2 5042.1 4845.5 5037.8 5052.6 5077.5 5122.0 4939.8 4955.4 4975.4 4984.6 5028.0 4935.3 4940.0 4951.8 4960.0 5005.3 4824.6 4929.4 4929.5 4933.2 4943.7 5189.5 5251.2 5289.0 5313.5 5334.7 5904.9 5908.0 5909.3 5910.6 5923.7 5792.5 5925.4 5934.1 5941.9 5947.3 5352.1 5452.4 5494.5 5528.5 5533.8 5957.1 5958.8 5976.0 5978.3 6039.0 5532.5 5768.7 5777.2 5782.4 5800.8 5772.8 5877.9 5889.7 5892.4 5896.5 5860.3 5903.2 5907.1 5911.5 5914.2 5899.9 5906.2 5907.0 5910.4 5917.9 4417.6 4490.8 4555.8 4561.7 4578.9 4051.8 4072.3 4076.4 4084.7 4115.3 5588.7 5608.0 5610.6 5612.4 5659.8 5691.9 5772.2 5810.5 5823.0 5891.9 5550.7 5614.1 5615.8 5616.6 5616.9 5186.1 5187.7 5196.7 5200.4 5251.9 5831.7 5940.8 5945.7 6000.6 6127.3 5931.2 5936.4 5938.2 5946.2 5960.7 6115.3 6122.4 6122.4 6129.2 6133.5 5681.0 5710.1 5722.0 5773.8 5827.2 5897.4 5904.8 5943.6 5945.7 5952.3 5602.8 5812.3 5822.7 5822.8 5824.3 5702.9 5706.6 5731.2 5800.9 5845.2 5610.4 5610.6 5611.7 5613.6 5614.2 5522.9 5727.3 5767.2 5770.8 5790.7 5602.7 5617.7 5693.5 5710.2 5750.8 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,751 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48991.46 Median:35123.82 stddev:85345.85 (243.0%) stddev-sans-first:85507.84 01:52:00 INFO - 2015-11-20 01:52:00,752 DEBUG : BROWSER_OUTPUT: Values: 572.2 582.3 588.1 589.1 597.7 31160.7 38761.0 39235.0 39324.6 39349.6 41089.6 41335.6 41631.6 41661.0 41676.4 7538.1 8694.3 8876.6 8879.2 8899.7 7624.0 10551.9 10793.3 10815.3 10819.0 10447.9 10466.6 10508.8 10545.9 10567.9 7706.8 7874.9 10416.3 10498.8 10578.8 10256.9 10378.3 10379.9 10386.5 10392.1 39718.0 40120.0 40205.2 40225.4 40239.0 3168.6 3206.9 3220.7 3599.7 3739.7 29560.6 29643.4 29647.9 29912.7 30528.8 3946.6 3993.1 4002.5 4005.2 4095.4 267992.0 268368.0 268644.0 269289.0 271000.0 55461.4 56471.4 59493.7 59822.7 60623.0 56528.2 56580.0 56772.4 56911.7 56998.0 15120.4 15367.4 15434.0 15439.9 15440.4 11274.4 11327.4 11347.3 11348.2 11351.5 19839.3 20155.4 20158.7 20198.4 20223.0 7907.4 8218.3 8219.1 8228.5 8253.8 918.4 920.9 1016.3 1031.1 1055.6 28116.7 28351.6 28476.1 28635.6 28659.7 1024.4 1039.3 1040.2 1044.3 1146.9 14742.7 14803.1 14914.3 14924.2 14935.6 396011.0 400691.0 401478.0 402016.0 402399.0 54232.5 54732.0 54792.5 54851.7 55002.4 1307.5 1314.5 1330.0 1336.2 1338.8 27258.6 27908.4 28203.1 28329.2 28440.6 43344.8 43935.0 43963.3 43999.0 44010.3 289125.0 290642.0 291169.0 291828.0 291885.0 27703.7 31129.2 31237.5 31375.7 31678.5 40222.2 40921.6 41269.6 41793.0 41970.6 35068.0 35179.6 35314.6 40868.0 41582.2 32467.7 38061.8 38722.2 40556.6 41079.6 37834.8 38157.6 38973.6 39006.8 39150.0 36607.5 38154.2 38799.6 39273.4 39763.6 27102.2 29063.0 29646.0 29705.4 29712.3 01:52:00 INFO - 2015-11-20 01:52:00,753 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,753 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29312.94 Median:339.43 stddev:93701.82 (27605.4%) stddev-sans-first:93939.42 01:52:00 INFO - 2015-11-20 01:52:00,753 DEBUG : BROWSER_OUTPUT: Values: 339.4 339.5 340.7 341.9 343.8 14616.0 15060.2 16192.8 16615.9 18679.7 15138.3 15217.1 15729.1 15759.8 15834.1 5016.3 5333.4 5338.2 5339.2 5345.8 3870.5 3960.8 5300.8 5700.0 5867.3 5781.6 5789.6 5792.8 5811.1 5822.4 5724.7 5854.3 5885.6 5892.9 5894.7 5088.9 5124.4 5128.2 5149.7 5156.5 11384.3 11556.4 19342.2 19798.8 19811.4 2106.7 2132.3 2134.7 2170.7 2390.0 8710.3 8770.9 12058.2 12687.5 12694.2 1676.4 1698.5 1791.9 1814.2 1869.5 58415.7 58536.1 59870.0 60305.0 61256.1 9807.0 10176.2 10183.0 10188.5 10191.1 13283.9 13297.8 13305.6 13325.0 13330.9 6937.0 7178.1 7320.2 7376.0 7548.1 6986.2 7001.8 7006.6 7020.4 7046.9 4096.1 4110.4 4128.9 4188.6 4398.3 1695.5 1736.3 1754.7 1764.5 1824.0 898.0 898.9 942.1 948.6 980.9 15715.9 15752.2 16017.8 16288.6 16366.7 906.8 974.7 996.8 1022.0 1052.1 9516.4 9655.0 9896.3 10021.9 10057.9 565122.0 567862.0 571322.0 577458.0 588175.0 73988.9 74408.0 74747.6 75067.6 76831.2 1151.2 1153.3 1201.2 1218.9 1237.3 14869.3 15095.2 15332.7 15371.5 15590.1 32512.3 33072.8 33383.0 33869.5 34685.0 44294.6 44887.3 46398.5 47731.0 51293.7 8741.2 9141.2 9166.5 9187.8 9206.5 10651.3 10749.8 13575.8 16412.5 16922.9 10378.5 11663.0 13787.0 14336.5 14436.1 13412.5 13726.8 13758.7 13956.2 14262.6 13819.2 14005.8 14249.7 14284.0 14351.0 8980.3 9021.5 9251.8 9845.6 9930.9 14568.9 14660.3 14739.9 15076.1 15122.7 01:52:00 INFO - 2015-11-20 01:52:00,753 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,753 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5239.88 Median:6320.61 stddev:2682.62 (42.4%) stddev-sans-first:2673.61 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: Values: 1277.6 1642.6 1681.8 1721.9 1726.3 6390.3 6984.2 7142.7 7178.0 7188.2 7235.2 7341.8 7364.7 7395.1 7399.2 2238.0 2255.4 2258.7 2260.2 2262.4 2249.9 2275.2 2282.4 2286.2 2288.4 2194.5 2208.2 2211.5 2218.9 2245.7 2186.7 2205.2 2210.9 2212.0 2213.7 2249.3 2253.2 2257.6 2258.9 2259.5 6395.6 6584.1 7316.3 7337.2 7337.7 4381.0 4481.2 4493.8 4513.9 4521.1 3084.7 3133.1 3141.8 3162.0 3287.7 1010.7 1012.1 1045.4 1051.3 1067.9 10002.9 10124.3 10128.9 10134.8 10163.6 4027.1 4083.7 4126.6 4147.4 4182.1 8373.6 8410.3 8413.5 8426.7 8440.3 4579.5 4579.9 4581.4 4582.0 4582.0 4177.8 4219.5 4221.8 4225.3 4226.6 3274.3 3354.5 3373.1 3373.6 3383.2 1022.4 1036.7 1040.6 1042.0 1045.4 1294.2 1314.7 1336.8 1340.8 1347.0 6889.7 7254.7 7258.1 7260.2 7267.8 468.4 476.0 478.4 479.3 481.3 5225.6 5701.9 5758.2 5759.9 5760.4 8148.5 8334.8 9001.2 9107.8 9136.5 8744.4 8980.6 8983.6 8986.9 9018.6 6755.9 6760.7 6794.0 6805.0 6861.1 6998.3 7053.8 7060.0 7081.3 7089.3 7846.3 7942.5 8100.9 8119.9 8145.3 8020.1 8163.6 8164.8 8327.5 8334.9 6494.5 6574.4 6575.2 6581.7 6584.3 7371.9 7399.8 7405.4 7412.9 7422.0 6756.1 6934.8 6936.1 6988.9 7069.7 6769.8 6852.8 6883.1 6907.4 6989.5 6890.8 6920.7 6983.0 6989.9 6996.9 6324.0 6354.1 6372.9 6381.4 6390.4 6317.2 6488.5 6527.3 6565.2 6566.6 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5308.95 Median:6047.86 stddev:1609.07 (26.6%) stddev-sans-first:1569.45 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: Values: 322.4 327.8 328.2 328.6 331.3 5946.4 6001.1 6010.7 6021.8 6072.4 5882.2 5890.3 5896.1 5932.3 5939.5 4538.6 4543.9 4547.2 4572.0 4577.2 4484.2 4547.4 4575.4 4578.7 4588.7 4501.1 4511.4 4513.3 4528.9 4533.8 4513.6 4516.1 4523.4 4527.8 4534.7 3847.8 3929.0 3929.6 3933.3 3941.8 5592.4 5662.4 5697.3 5699.2 5735.7 5257.1 5259.4 5268.2 5278.1 5278.5 6028.5 6113.4 6137.3 6253.1 6349.0 4391.8 4409.4 4413.0 4416.9 4420.7 6481.7 6558.1 6560.7 6572.7 6578.0 4408.5 4500.1 4509.1 4514.8 4520.5 4561.6 4574.0 4575.0 4576.3 4578.0 5259.7 5273.2 5273.6 5274.3 5282.4 6368.4 6370.6 6380.9 6383.2 6384.7 1576.5 1619.0 1622.2 1629.8 1660.4 1088.3 1090.4 1092.7 1096.9 1103.2 6037.6 6063.1 6064.8 6071.1 6072.7 6490.6 6491.0 6492.9 6496.1 6498.5 6058.1 6061.6 6062.4 6066.1 6069.1 3477.8 3559.0 3566.0 3566.9 3567.4 6806.3 6807.1 6817.8 6821.7 6821.7 6726.8 6733.9 6823.7 6824.0 6826.9 6583.0 6714.0 6717.8 6881.7 6901.7 6508.6 6612.4 6641.0 6647.8 6648.8 6516.0 6541.5 6548.8 6551.1 6560.7 6733.3 6872.2 6873.3 6874.0 6881.7 4392.5 4421.7 4424.5 4424.8 4428.9 6609.8 6611.6 6618.3 6620.0 6626.9 6297.3 6423.4 6437.0 6442.7 6444.1 6377.5 6391.6 6393.4 6393.6 6394.8 6189.3 6205.5 6220.8 6227.4 6229.3 6305.8 6435.4 6436.3 6438.9 6458.5 6282.6 6302.5 6303.2 6308.5 6310.5 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13627.70 Median:24118.93 stddev:15827.20 (65.6%) stddev-sans-first:15842.05 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: Values: 749.1 819.6 834.1 838.9 866.9 12736.3 12961.2 13010.5 13011.2 13011.7 12117.6 12194.8 12199.9 12204.6 12205.3 669.8 683.1 812.5 822.9 827.6 811.1 813.2 813.3 813.7 815.1 707.5 854.0 854.1 856.4 856.5 715.3 851.0 868.3 869.2 870.3 628.7 630.8 632.0 632.6 632.8 12284.9 12320.5 12331.7 12361.1 12416.6 11957.9 12229.4 12315.9 12562.7 12576.2 9855.2 9924.7 10200.7 10462.8 10755.5 3508.5 3553.1 3608.5 3635.6 3696.9 47760.0 47823.3 47884.0 48055.5 49294.5 9041.6 9067.9 9101.4 9257.8 9360.2 17997.0 18020.8 18044.6 18210.9 18272.4 7696.3 7827.8 7866.4 7873.2 7928.3 4345.4 4346.8 4364.2 4414.1 4429.5 2996.2 3026.8 3035.1 3043.3 3045.8 561.2 565.9 566.2 570.6 571.6 614.6 628.9 635.2 644.3 727.7 8211.4 8290.0 8314.0 8346.4 8367.4 477.9 619.8 653.1 674.7 680.9 4062.2 4138.3 4150.2 4217.3 4339.7 55965.4 56868.1 57226.0 57523.0 57954.4 49960.3 51803.7 51822.2 52042.7 52184.5 45021.8 45114.8 45240.0 46094.5 46667.0 8232.4 8734.9 8805.7 8895.6 9100.5 32173.8 32308.2 33062.8 33746.7 34133.1 43856.6 44161.8 44403.0 44637.3 45477.8 10903.3 10915.8 11006.7 11070.4 11137.8 12383.4 12516.1 12545.3 12659.1 12786.2 10918.5 10978.2 11233.3 11237.9 11597.8 11041.3 11203.4 11374.4 11761.0 11770.0 10823.3 11171.4 11172.5 11295.8 11514.1 9463.2 9750.5 9843.4 10018.0 10395.6 9200.8 9248.9 9313.6 9330.4 9462.9 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:52:00 INFO - 2015-11-20 01:52:00,755 DEBUG : BROWSER_OUTPUT: 01:52:01 INFO - 2015-11-20 01:52:01,422 INFO : Browser exited with error code: 0 01:52:01 INFO - 2015-11-20 01:52:01,443 INFO : Completed test dromaeo_css (00:18:34) 01:52:01 INFO - 2015-11-20 01:52:01,443 INFO : Starting test kraken 01:52:01 INFO - 2015-11-20 01:52:01,443 DEBUG : operating with platform_type : linux_ 01:52:01 INFO - 2015-11-20 01:52:01,443 INFO : Initialising browser for kraken test... 01:52:01 INFO - 2015-11-20 01:52:01,449 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfgDOFl/profile http://localhost:42604/getInfo.html 01:52:05 INFO - 2015-11-20 01:52:05,250 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:52:05 INFO - 2015-11-20 01:52:05,250 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:52:05 INFO - 2015-11-20 01:52:05,250 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:52:05 INFO - 2015-11-20 01:52:05,250 DEBUG : BROWSER_OUTPUT: __metrics 01:52:05 INFO - 2015-11-20 01:52:05,327 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:52:06 INFO - 2015-11-20 01:52:06,617 INFO : Browser initialized. 01:52:06 INFO - 2015-11-20 01:52:06,618 INFO : Running cycle 1/1 for kraken test... 01:52:06 INFO - 2015-11-20 01:52:06,618 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfgDOFl/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:52:18 INFO - 2015-11-20 01:52:18,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 141877248 01:52:18 INFO - 2015-11-20 01:52:18,395 DEBUG : BROWSER_OUTPUT: 01:52:20 INFO - 2015-11-20 01:52:20,849 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42604/tests/kraken/driver.html?testName=audio-beat-detection) 01:52:21 INFO - 2015-11-20 01:52:21,442 DEBUG : BROWSER_OUTPUT: RSS: Main: 180092928 01:52:21 INFO - 2015-11-20 01:52:21,442 DEBUG : BROWSER_OUTPUT: 01:52:24 INFO - 2015-11-20 01:52:24,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42604/tests/kraken/driver.html?testName=audio-dft) 01:52:24 INFO - 2015-11-20 01:52:24,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 169508864 01:52:24 INFO - 2015-11-20 01:52:24,660 DEBUG : BROWSER_OUTPUT: 01:52:27 INFO - 2015-11-20 01:52:27,524 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42604/tests/kraken/driver.html?testName=audio-fft) 01:52:28 INFO - 2015-11-20 01:52:28,085 DEBUG : BROWSER_OUTPUT: RSS: Main: 151064576 01:52:28 INFO - 2015-11-20 01:52:28,086 DEBUG : BROWSER_OUTPUT: 01:52:30 INFO - 2015-11-20 01:52:30,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42604/tests/kraken/driver.html?testName=audio-oscillator) 01:52:30 INFO - 2015-11-20 01:52:30,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 154472448 01:52:30 INFO - 2015-11-20 01:52:30,623 DEBUG : BROWSER_OUTPUT: 01:52:32 INFO - 2015-11-20 01:52:32,763 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42604/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:52:33 INFO - 2015-11-20 01:52:33,326 DEBUG : BROWSER_OUTPUT: RSS: Main: 156676096 01:52:33 INFO - 2015-11-20 01:52:33,326 DEBUG : BROWSER_OUTPUT: 01:52:36 INFO - 2015-11-20 01:52:36,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42604/tests/kraken/driver.html?testName=imaging-darkroom) 01:52:36 INFO - 2015-11-20 01:52:36,777 DEBUG : BROWSER_OUTPUT: RSS: Main: 155279360 01:52:36 INFO - 2015-11-20 01:52:36,777 DEBUG : BROWSER_OUTPUT: 01:52:40 INFO - 2015-11-20 01:52:40,189 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42604/tests/kraken/driver.html?testName=imaging-desaturate) 01:52:40 INFO - 2015-11-20 01:52:40,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 157519872 01:52:40 INFO - 2015-11-20 01:52:40,764 DEBUG : BROWSER_OUTPUT: 01:52:43 INFO - 2015-11-20 01:52:43,521 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42604/tests/kraken/driver.html?testName=json-parse-financial) 01:52:44 INFO - 2015-11-20 01:52:44,087 DEBUG : BROWSER_OUTPUT: RSS: Main: 156241920 01:52:44 INFO - 2015-11-20 01:52:44,087 DEBUG : BROWSER_OUTPUT: 01:52:45 INFO - 2015-11-20 01:52:45,635 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42604/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:52:46 INFO - 2015-11-20 01:52:46,200 DEBUG : BROWSER_OUTPUT: RSS: Main: 167866368 01:52:46 INFO - 2015-11-20 01:52:46,200 DEBUG : BROWSER_OUTPUT: 01:52:48 INFO - 2015-11-20 01:52:48,012 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:52:48 INFO - 2015-11-20 01:52:48,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 166211584 01:52:48 INFO - 2015-11-20 01:52:48,605 DEBUG : BROWSER_OUTPUT: 01:52:50 INFO - 2015-11-20 01:52:50,685 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:52:51 INFO - 2015-11-20 01:52:51,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 177819648 01:52:51 INFO - 2015-11-20 01:52:51,259 DEBUG : BROWSER_OUTPUT: 01:52:53 INFO - 2015-11-20 01:52:53,652 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:52:54 INFO - 2015-11-20 01:52:54,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 182988800 01:52:54 INFO - 2015-11-20 01:52:54,233 DEBUG : BROWSER_OUTPUT: 01:52:56 INFO - 2015-11-20 01:52:56,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:52:57 INFO - 2015-11-20 01:52:57,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 180473856 01:52:57 INFO - 2015-11-20 01:52:57,368 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42604/tests/kraken/driver.html?testName=ai-astar) 01:52:59 INFO - 2015-11-20 01:52:59,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 239964160 01:52:59 INFO - 2015-11-20 01:52:59,287 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,287 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:52:59 INFO - 2015-11-20 01:52:59,287 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:52:59 INFO - 2015-11-20 01:52:59,288 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:52:59 INFO - 2015-11-20 01:52:59,288 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:52:59 INFO - 2015-11-20 01:52:59,288 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;111;106;111;111;112;101;110;109;108 01:52:59 INFO - 2015-11-20 01:52:59,288 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;124;148;167;125;156;150;144;168;124 01:52:59 INFO - 2015-11-20 01:52:59,288 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;161;162;161;161;160;162;161;160 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;89;96;72;72;72;105;97;71 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;91;135;91;91;91;136;89;90;136 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;109;109;109;109;109;110 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;145;145;145;145;145;145 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;95;96;95;95;95;95 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;57;59;59;59;58;60;66;58;57 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;69;69;69;69;70;70;70;69 01:52:59 INFO - 2015-11-20 01:52:59,289 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;94;86;85;93;85;86;84;85;85 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;118;117;114;123;116;124;115;114;114 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;150;163;141;149;139;160;159;146;142 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;67;65;66;67;67;67;67;68;66 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,931 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:52:59 INFO - 2015-11-20 01:52:59,290 DEBUG : BROWSER_OUTPUT: __startTimestamp1448013179281__endTimestamp 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: Number of tests: 14 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.00 Median:111.00 stddev:3.33 (3.0%) stddev-sans-first:3.46 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 106.0 111.0 111.0 112.0 101.0 110.0 109.0 108.0 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.70 Median:150.50 stddev:16.61 (11.0%) stddev-sans-first:17.50 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: Values: 151.0 124.0 148.0 167.0 125.0 156.0 150.0 144.0 168.0 124.0 01:52:59 INFO - 2015-11-20 01:52:59,291 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 161.0 162.0 161.0 161.0 160.0 162.0 161.0 160.0 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.80 Median:72.00 stddev:13.41 (18.6%) stddev-sans-first:13.75 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 89.0 96.0 72.0 72.0 72.0 105.0 97.0 71.0 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.10 Median:91.00 stddev:21.79 (23.9%) stddev-sans-first:22.59 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: Values: 91.0 91.0 135.0 91.0 91.0 91.0 136.0 89.0 90.0 136.0 01:52:59 INFO - 2015-11-20 01:52:59,292 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 01:52:59 INFO - 2015-11-20 01:52:59,293 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.10 Median:59.00 stddev:2.60 (4.4%) stddev-sans-first:2.73 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: Values: 58.0 57.0 59.0 59.0 59.0 58.0 60.0 66.0 58.0 57.0 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:0.63 (0.9%) stddev-sans-first:0.50 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 69.0 69.0 69.0 69.0 70.0 70.0 70.0 69.0 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.00 stddev:3.54 (4.1%) stddev-sans-first:3.74 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: Values: 86.0 94.0 86.0 85.0 93.0 85.0 86.0 84.0 85.0 85.0 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.50 Median:117.50 stddev:3.72 (3.2%) stddev-sans-first:3.83 01:52:59 INFO - 2015-11-20 01:52:59,294 DEBUG : BROWSER_OUTPUT: Values: 120.0 118.0 117.0 114.0 123.0 116.0 124.0 115.0 114.0 114.0 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.00 Median:149.50 stddev:8.84 (5.9%) stddev-sans-first:8.89 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: Values: 141.0 150.0 163.0 141.0 149.0 139.0 160.0 159.0 146.0 142.0 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 65.0 66.0 67.0 67.0 67.0 67.0 68.0 66.0 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:52:59 INFO - 2015-11-20 01:52:59,295 DEBUG : BROWSER_OUTPUT: 01:53:00 INFO - 2015-11-20 01:53:00,158 INFO : Browser exited with error code: 0 01:53:00 INFO - 2015-11-20 01:53:00,209 INFO : Completed test kraken (00:00:58) 01:53:00 INFO - 2015-11-20 01:53:00,209 INFO : Starting test v8_7 01:53:00 INFO - 2015-11-20 01:53:00,209 DEBUG : operating with platform_type : linux_ 01:53:00 INFO - 2015-11-20 01:53:00,209 INFO : Initialising browser for v8_7 test... 01:53:00 INFO - 2015-11-20 01:53:00,215 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqjBYo8/profile http://localhost:42604/getInfo.html 01:53:03 INFO - 2015-11-20 01:53:03,955 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:53:03 INFO - 2015-11-20 01:53:03,956 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:53:03 INFO - 2015-11-20 01:53:03,956 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:53:03 INFO - 2015-11-20 01:53:03,956 DEBUG : BROWSER_OUTPUT: __metrics 01:53:04 INFO - 2015-11-20 01:53:04,099 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:53:05 INFO - 2015-11-20 01:53:05,434 INFO : Browser initialized. 01:53:05 INFO - 2015-11-20 01:53:05,434 INFO : Running cycle 1/1 for v8_7 test... 01:53:05 INFO - 2015-11-20 01:53:05,435 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqjBYo8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:53:17 INFO - 2015-11-20 01:53:17,159 DEBUG : BROWSER_OUTPUT: RSS: Main: 141807616 01:53:17 INFO - 2015-11-20 01:53:17,159 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42604/tests/v8_7/run.html (next: http://localhost:42604/tests/v8_7/run.html) 01:53:38 INFO - 2015-11-20 01:53:38,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 541138944 01:53:38 INFO - 2015-11-20 01:53:38,751 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: |0;Richards;163.07893020221786 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.87359441644048 01:53:38 INFO - 2015-11-20 01:53:38,753 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.4950213371266 01:53:38 INFO - 2015-11-20 01:53:38,754 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5030.150753768844 01:53:38 INFO - 2015-11-20 01:53:38,754 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1095.290251916758 01:53:38 INFO - 2015-11-20 01:53:38,754 DEBUG : BROWSER_OUTPUT: |5;Earley;778.816199376947 01:53:38 INFO - 2015-11-20 01:53:38,754 DEBUG : BROWSER_OUTPUT: |6;Boyer;12108.43373493976 01:53:38 INFO - 2015-11-20 01:53:38,754 DEBUG : BROWSER_OUTPUT: |7;RegExp;26342.105263157893 01:53:38 INFO - 2015-11-20 01:53:38,755 DEBUG : BROWSER_OUTPUT: |8;Splay;593.1198102016607 01:53:38 INFO - 2015-11-20 01:53:38,755 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 01:53:38 INFO - 2015-11-20 01:53:38,755 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:53:38 INFO - 2015-11-20 01:53:38,755 DEBUG : BROWSER_OUTPUT: __startTimestamp1448013218751__endTimestamp 01:53:38 INFO - 2015-11-20 01:53:38,755 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,756 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:53:38 INFO - 2015-11-20 01:53:38,756 DEBUG : BROWSER_OUTPUT: Number of tests: 10 01:53:38 INFO - 2015-11-20 01:53:38,756 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,756 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.08 Median:163.08 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,756 DEBUG : BROWSER_OUTPUT: Values: 163.1 01:53:38 INFO - 2015-11-20 01:53:38,756 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.87 Median:193.87 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: Values: 193.9 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.50 Median:284.50 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: Values: 284.5 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5030.15 Median:5030.15 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,757 DEBUG : BROWSER_OUTPUT: Values: 5030.2 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1095.29 Median:1095.29 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: Values: 1095.3 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:778.82 Median:778.82 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: Values: 778.8 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12108.43 Median:12108.43 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: Values: 12108.4 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26342.11 Median:26342.11 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,758 DEBUG : BROWSER_OUTPUT: Values: 26342.1 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.12 Median:593.12 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: Values: 593.1 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: Values: 4826.9 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:53:38 INFO - 2015-11-20 01:53:38,759 DEBUG : BROWSER_OUTPUT: 01:53:39 INFO - 2015-11-20 01:53:39,601 INFO : Browser exited with error code: 0 01:53:39 INFO - 2015-11-20 01:53:39,616 INFO : Completed test v8_7 (00:00:39) 01:53:39 INFO - 2015-11-20 01:53:39,836 INFO : Completed test suite (00:20:12) 01:53:39 INFO - 2015-11-20 01:53:39,836 DEBUG : Working with test: dromaeo_css 01:53:39 INFO - 2015-11-20 01:53:39,836 DEBUG : Generating results file: dromaeo_css 01:53:39 INFO - 2015-11-20 01:53:39,837 DEBUG : Working with test: kraken 01:53:39 INFO - 2015-11-20 01:53:39,837 DEBUG : Generating results file: kraken 01:53:39 INFO - 2015-11-20 01:53:39,838 INFO : javascript benchmark 01:53:39 INFO - 2015-11-20 01:53:39,838 DEBUG : Working with test: v8_7 01:53:39 INFO - 2015-11-20 01:53:39,838 DEBUG : Generating results file: v8_7 01:53:39 INFO - 2015-11-20 01:53:39,838 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:53:39 INFO - 2015-11-20 01:53:39,884 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 01:53:39 INFO - 2015-11-20 01:53:39,885 DEBUG : process_Request line: dromaeo_css 6965.32 graph.html#tests=[[72,64,35]] 01:53:39 INFO - 2015-11-20 01:53:39,885 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:53:39 INFO - 2015-11-20 01:53:39,913 DEBUG : process_Request line: kraken 1499.40 graph.html#tests=[[232,64,35]] 01:53:39 INFO - 2015-11-20 01:53:39,913 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:53:39 INFO - 2015-11-20 01:53:39,940 DEBUG : process_Request line: v8_7 21122.64 graph.html#tests=[[230,64,35]] 01:53:39 INFO - 2015-11-20 01:53:39,941 INFO : javascript benchmark 01:53:39 INFO - 2015-11-20 01:53:39,945 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3189.856456459459, 3253.5119732040175, 3293.8188399638025, 3298.356820420016, 3317.2702005558785, 5187.040397526451, 5285.973570132153, 5387.568994480442, 5397.136458166694, 5423.294971653684, 5396.406395296514, 5401.78392864285, 5462.126059830428, 5467.097928841737, 5521.0, 4942.999999999991, 4977.303177555145, 5002.474740152286, 5024.221245706921, 5042.067217564762, 4845.491223421525, 5037.823676171335, 5052.646314757969, 5077.517635824609, 5122.0, 4939.753012349368, 4955.380577427822, 4975.378077740283, 4984.601231901475, 5028.019536190444, 4935.333729946447, 4940.0, 4951.752412379403, 4960.032793605248, 5005.34930459038, 4824.5899098576665, 4929.383827021622, 4929.457759646436, 4933.1860243059855, 4943.678660887031, 5189.481051894818, 5251.186066159751, 5288.9735551322, 5313.495217954288, 5334.67991920486, 5904.911426328608, 5908.02517584598, 5909.349971503131, 5910.586258961875, 5923.733431995571, 5792.478676919098, 5925.4370834770625, 5934.050551911717, 5941.940580594225, 5947.345791962881, 5352.090144675414, 5452.427495113036, 5494.5054945054735, 5528.502434780889, 5533.806316778893, 5957.076653118774, 5958.791442299499, 5975.970120149372, 5978.252718410199, 6039.0, 5532.529734972572, 5768.711564421762, 5777.19119323295, 5782.392848750905, 5800.767969281266, 5772.797952071742, 5877.882442351129, 5889.705514724246, 5892.381299963527, 5896.469317761422, 5860.267466566679, 5903.203067585821, 5907.1139329100115, 5911.527077833763, 5914.201582786355, 5899.852503687356, 5906.202662640574, 5906.970465147647, 5910.379410161871, 5917.881642367214, 4417.580329868657, 4490.753008584494, 4555.794989225493, 4561.680682352204, 4578.862634120915, 4051.7974101294817, 4072.3280658690983, 4076.449679293317, 4084.6528045116497, 4115.300398932189, 5588.720563971785, 5608.046632072557, 5610.551155907518, 5612.410696876769, 5659.801906933238, 5691.943080569225, 5772.163036359789, 5810.477057064884, 5823.039198532279, 5891.852703682443, 5550.6669599823335, 5614.129809879475, 5615.775368985276, 5616.5787565932715, 5616.859578510488, 5186.118359878829, 5187.688738675692, 5196.662216955886, 5200.427952925175, 5251.868703282372, 5831.650100993954, 5940.821775346746, 5945.672987985702, 6000.609960352563, 6127.29536103336, 5931.228940237742, 5936.406359364029, 5938.168656388023, 5946.226990491208, 5960.672163031075, 6115.266168059861, 6122.418370254819, 6122.448979591858, 6129.172561704113, 6133.539984501091, 5681.034224181982, 5710.114932185518, 5721.971390143189, 5773.797917072882, 5827.184194212899, 5897.3807750185615, 5904.763809447574, 5943.554233432424, 5945.732442040205, 5952.285725712942, 5602.803901863415, 5812.273465816773, 5822.70886455684, 5822.796202132905, 5824.301083869963, 5702.914456283076, 5706.629069110494, 5731.168980497888, 5800.883982320414, 5845.18167456565, 5610.382857885547, 5610.607257491936, 5611.747471363717, 5613.635113717595, 5614.242077319673, 5522.917156242579, 5727.31272247333, 5767.221425107725, 5770.769169233184, 5790.681512516852, 5602.747876345493, 5617.747201375866, 5693.459121383462, 5710.172025056427, 5750.827475175752], "unit": "ms", "name": "dojo.html", "value": 5413.866290519175}, {"lowerIsBetter": false, "replicates": [572.1989215098864, 582.263436752508, 588.1325045557803, 589.089856172214, 597.7220592424527, 31160.688393116063, 38761.0, 39235.01912452187, 39324.60675393245, 39349.60650393495, 41089.58910410895, 41335.58664413355, 41631.583684163146, 41661.0, 41676.37485437721, 7538.1331146918255, 8694.304455643549, 8876.556172191396, 8879.245264152523, 8899.73300800977, 7624.046994125734, 10551.894481055206, 10793.2984356017, 10815.297005694605, 10819.026287634111, 10447.895521044768, 10466.58133674656, 10508.842367364496, 10545.894541054568, 10567.947160264226, 7706.7687969361, 7874.881876771853, 10416.27086103973, 10498.790024199512, 10578.841317380246, 10256.897431025669, 10378.325408848401, 10379.948100259526, 10386.532606032746, 10392.116670082976, 39718.0, 40120.0, 40205.19589608192, 40225.396619050734, 40239.0, 3168.556402103707, 3206.887758928426, 3220.6940340667597, 3599.748017638766, 3739.719521035933, 29560.556591651144, 29643.407131857242, 29647.851760741272, 29912.70087299121, 30528.84735576308, 3946.565877753445, 3993.0815912340327, 4002.519697636273, 4005.178938317638, 4095.4061661059277, 267992.0, 268368.0, 268644.0, 269289.0, 271000.0, 55461.44538554644, 56471.43528564662, 59493.70253148793, 59822.70088649529, 60623.0, 56528.15207771887, 56580.0, 56772.432275677544, 56911.71544142336, 56998.0, 15120.412356702607, 15367.385304587915, 15434.0, 15439.915611392318, 15440.382384704486, 11274.436278186222, 11327.376994265394, 11347.319160850375, 11348.205625606128, 11351.489182986785, 19839.30562430308, 20155.39533813988, 20158.69761953572, 20198.39404817858, 20222.988850557413, 7907.367410607138, 8218.301444377295, 8219.095899451055, 8228.50628962264, 8253.793655158548, 918.4397517514311, 920.9171174594319, 1016.2936758952457, 1031.097789434245, 1055.5935964653663, 28116.71883281182, 28351.640735992172, 28476.145715628565, 28635.57046644302, 28659.713402865706, 1024.416082832793, 1039.3036665434176, 1040.2250323509033, 1044.2742294105676, 1146.8853114688463, 14742.705145897022, 14803.111813291236, 14914.254287285592, 14924.179170145746, 14935.626609334853, 396011.0, 400691.0, 401478.0, 402016.0, 402399.0, 54232.45767542274, 54732.0, 54792.45207547953, 54851.72574137104, 55002.44997550053, 1307.4900788692419, 1314.4610709609206, 1330.0490149543123, 1336.1982810313928, 1338.765715999696, 27258.591121132802, 27908.441831162858, 28203.091647389083, 28329.150125496268, 28440.57339139956, 43344.78327608468, 43935.0, 43963.34054989242, 43999.0, 44010.33984490299, 289125.0, 290642.0, 291169.0, 291828.0, 291885.0, 27703.72296277092, 31129.221769455038, 31237.531437028007, 31375.686243137276, 31678.524822127227, 40222.1955560893, 40921.59078409178, 41269.58730412657, 41793.0, 41970.58029419789, 35068.0, 35179.64820351764, 35314.64685353216, 40868.0, 41582.1683566333, 32467.675323246465, 38061.80969095137, 38722.22555548817, 40556.59443405528, 41079.58920410758, 37834.81082594569, 38157.61842381541, 38973.61026389813, 39006.80496597499, 39150.0, 36607.45088823616, 38154.236915262096, 38799.6120038796, 39273.410898835966, 39763.60236397599, 27102.186934392, 29063.0, 29646.0, 29705.405891882474, 29712.257193570334], "unit": "ms", "name": "ext.html", "value": 19061.15737996843}, {"lowerIsBetter": false, "replicates": [339.3603058235226, 339.50601874272934, 340.69337596163433, 341.9247765491591, 343.75593328736596, 14616.0, 15060.24698765063, 16192.757108643365, 16615.916920415395, 18679.71980420295, 15138.318775655094, 15217.086974781521, 15729.05625662461, 15759.763603545927, 15834.129122898235, 5016.322796422482, 5333.359996800389, 5338.225957236198, 5339.225812257239, 5345.812896548621, 3870.5161854768153, 3960.8415663373576, 5300.840974770763, 5700.0, 5867.295924489067, 5781.595288329819, 5789.594728369016, 5792.7972520961775, 5811.0702287633985, 5822.3886491918165, 5724.656520608777, 5854.268216472941, 5885.61743486672, 5892.882142357172, 5894.675792831393, 5088.931324421839, 5124.436312005713, 5128.230765385222, 5149.716765577892, 5156.458571849939, 11384.260023098555, 11556.364399957998, 19342.226310947546, 19798.80201197984, 19811.405657830288, 2106.65240235361, 2132.2813412898677, 2134.6584546472645, 2170.6526955687013, 2390.032037025008, 8710.346723995663, 8770.912290877073, 12058.15592908488, 12687.492500299977, 12694.174878632859, 1676.4383931383002, 1698.4904528641482, 1791.9104044797707, 1814.2289526951124, 1869.5419622192514, 58415.70792146055, 58536.12195817066, 59870.0, 60305.0, 61256.081158782654, 9807.0, 10176.236782241362, 10183.0, 10188.541515631814, 10191.133753631024, 13283.933580332037, 13297.800532992012, 13305.600831975056, 13325.000624953167, 13330.866691332962, 6937.0, 7178.066851309401, 7320.194778574352, 7375.963120184365, 7548.056492938383, 6986.16166060076, 7001.824954376181, 7006.614636195059, 7020.438364930793, 7046.859062818715, 4096.09885825124, 4110.403991421287, 4128.8852009957145, 4188.560201178893, 4398.296272596369, 1695.5252529291572, 1736.3054778088865, 1754.6666133434608, 1764.4971183212979, 1823.972640410395, 897.9551022448851, 898.8876390451194, 942.072059021863, 948.6395169835419, 980.9068138526828, 15715.921420393053, 15752.212389380486, 16017.759733604005, 16288.592785180464, 16366.672666546603, 906.7733066733317, 974.668612671695, 996.7906739584769, 1022.0188618925914, 1052.084686322904, 9516.38143520669, 9655.0, 9896.25778066648, 10021.949890250648, 10057.899421005697, 565122.0, 567862.0, 571322.0, 577458.0, 588175.0, 73988.89016664754, 74408.0, 74747.62626186834, 75067.62466187634, 76831.2316876824, 1151.228676786555, 1153.2561497834017, 1201.1531870031606, 1218.939053047344, 1237.2823762218072, 14869.256537173314, 15095.207386633068, 15332.693346133237, 15371.538853834403, 15590.142542159836, 32512.34975300528, 33072.834635826664, 33383.0, 33869.491957620165, 34685.0, 44294.557054430334, 44887.32669010033, 46398.53601463942, 47731.0, 51293.7435312821, 8741.169588889044, 9141.222996045411, 9166.450012999241, 9187.816243675223, 9206.493642849708, 10651.254412191072, 10749.838752418877, 13575.79636305476, 16412.507624771275, 16922.915385422995, 10378.532966016699, 11663.0, 13787.0, 14336.49822256216, 14436.061655992327, 13412.463501459832, 13726.794098088736, 13758.65603359924, 13956.162630242217, 14262.643433914234, 13819.170849749047, 14005.809136914037, 14249.715005700034, 14284.0, 14351.0, 8980.281577473917, 9021.503817290111, 9251.76870578241, 9845.60617575289, 9930.900690992998, 14568.854311457173, 14660.266986650839, 14739.92630036843, 15076.095434273979, 15122.697546049238], "unit": "ms", "name": "jquery.html", "value": 8692.631451156763}, {"lowerIsBetter": false, "replicates": [1277.6039427777384, 1642.622196894714, 1681.756145358923, 1721.8880772749774, 1726.2577091850499, 6390.297067322591, 6984.196817366004, 7142.678579463924, 7178.0, 7188.209296977335, 7235.204127545966, 7341.779746607597, 7364.705411783522, 7395.075615548057, 7399.223081576438, 2238.0488292475675, 2255.436140964759, 2258.706368172135, 2260.1750361118156, 2262.3552287598036, 2249.876256805875, 2275.24916777463, 2282.3609389371004, 2286.1884031168943, 2288.3592594073684, 2194.5281764420647, 2208.2271205078255, 2211.5355775287208, 2218.9334319970344, 2245.719285089364, 2186.650135978236, 2205.228170140454, 2210.9447263818374, 2212.0156530344, 2213.701150344707, 2249.3139592424304, 2253.177590179589, 2257.57106149831, 2258.8757618330983, 2259.4803195265017, 6395.584287021329, 6584.078229047937, 7316.341529262392, 7337.156227033906, 7337.74317898871, 4381.036172042146, 4481.193385190664, 4493.752843593601, 4513.864584062483, 4521.095780843844, 3084.6761090085442, 3133.075742655922, 3141.7643676724338, 3161.9525707114412, 3287.7369810415107, 1010.6765834932825, 1012.1447376966452, 1045.3518818332682, 1051.3008849115329, 1067.8505009298703, 10002.949985250027, 10124.29129960903, 10128.949355253177, 10134.797304053951, 10163.59345626174, 4027.1341661542324, 4083.734557253769, 4126.566710495415, 4147.419361289422, 4182.121754431542, 8373.623186956602, 8410.285125764256, 8413.537255450887, 8426.662933482592, 8440.324774018063, 4579.473360563511, 4579.885502862455, 4581.381513495636, 4581.977090114595, 4582.037772067836, 4177.770222637723, 4219.51475580306, 4221.810018549173, 4225.260579398593, 4226.57734226581, 3274.279658475132, 3354.4968254761493, 3373.072405088571, 3373.6120346160014, 3383.154211447138, 1022.3712416863575, 1036.652721338345, 1040.604570263295, 1041.9739506512399, 1045.4093437207953, 1294.249335385474, 1314.7436249931172, 1336.8195293635429, 1340.8390993080698, 1346.9964876167123, 6889.724411023603, 7254.746083887038, 7258.0927384076995, 7260.165081015751, 7267.818304542428, 468.3793972985807, 475.9857204283877, 478.4330568276603, 479.2547588499846, 481.2876942125674, 5225.608079394061, 5701.885962280731, 5758.193852860602, 5759.85600359986, 5760.36635970043, 8148.511089334659, 8334.791630209173, 9001.18989290967, 9107.772305692279, 9136.543172841464, 8744.387892847566, 8980.640774368952, 8983.595738191798, 8986.86519702205, 9018.639254429749, 6755.932440675629, 6760.7295708172105, 6794.048833163361, 6805.0, 6861.073755043005, 6998.2651821558, 7053.753118640823, 7060.046893669289, 7081.291870812877, 7089.291070892869, 7846.33306168982, 7942.483738556975, 8100.918990810016, 8119.878201826859, 8145.348372130334, 8020.117787043304, 8163.591820409075, 8164.7550573482895, 8327.541985190872, 8334.916650833415, 6494.480441564758, 6574.4411725002, 6575.178102737158, 6581.6709164540625, 6584.275729669828, 7371.889421658787, 7399.778006659809, 7405.370543503863, 7412.851742965218, 7421.962890185515, 6756.121704178622, 6934.826629334105, 6936.098307220231, 6988.860222795617, 7069.71721131149, 6769.796906092825, 6852.828679283058, 6883.105196324646, 6907.412869905913, 6989.545679530815, 6890.827729306808, 6920.653967301715, 6983.0, 6989.860202796017, 6996.895046574407, 6323.9683801580695, 6354.078658594571, 6372.904406433814, 6381.3937675920715, 6390.360963903572, 6317.241930968129, 6488.513361497812, 6527.281998980204, 6565.244996825426, 6566.6388348639375], "unit": "ms", "name": "mootools.html", "value": 4265.483856808866}, {"lowerIsBetter": false, "replicates": [322.36976710530917, 327.8033180091945, 328.19755697320045, 328.6155198417851, 331.26789794554065, 5946.405359464057, 6001.099835024743, 6010.6994650267425, 6021.789237376693, 6072.392760723926, 5882.235309409782, 5890.293164820217, 5896.086106653475, 5932.288125424944, 5939.52483801296, 4538.591526762591, 4543.90912181757, 4547.18150732868, 4571.977140114294, 4577.176108300505, 4484.215262329096, 4547.408836851205, 4575.428071491064, 4578.702384345023, 4588.678792484528, 4501.099780043988, 4511.368408422823, 4513.300438432048, 4528.932066019012, 4533.795979180928, 4513.571210734975, 4516.1193567254395, 4523.389342438778, 4527.818887244539, 4534.659900507459, 3847.826847791828, 3928.978465598936, 3929.6266854649048, 3933.311670457687, 3941.763494190329, 5592.356878958891, 5662.37713851476, 5697.287839020122, 5699.202111704365, 5735.655860648333, 5257.106291930381, 5259.421463638997, 5268.209768534713, 5278.076336641073, 5278.47215278473, 6028.457438830483, 6113.388661133851, 6137.324894261627, 6253.062040693932, 6348.968255158741, 4391.758453285068, 4409.360642706822, 4413.029133590605, 4416.911661766747, 4420.734755914623, 6481.675916204171, 6558.081868538408, 6560.737570497175, 6572.737090516373, 6577.967110164419, 4408.515063343029, 4500.122476117192, 4509.075639493896, 4514.796834142471, 4520.52534483881, 4561.589456948825, 4574.0, 4574.970631607928, 4576.290674945389, 4577.954220457819, 5259.710715910662, 5273.209018647233, 5273.604479664041, 5274.287971123926, 5282.392524859613, 6368.363163683687, 6370.649614271259, 6380.936190638127, 6383.202099737533, 6384.744610215632, 1576.4797616786434, 1618.9514314570347, 1622.1564786311053, 1629.8125715542628, 1660.418853401324, 1088.2817340555355, 1090.4438736244413, 1092.6612750047511, 1096.8628921384827, 1103.227740581596, 6037.637741735422, 6063.060225665029, 6064.75740970365, 6071.119687645267, 6072.666003369857, 6490.610563366213, 6490.967545162244, 6492.870142597121, 6496.123023391876, 6498.480121590262, 6058.091286307001, 6061.636301821904, 6062.42406971337, 6066.090086486974, 6069.089636554552, 3477.756557040983, 3559.039059453933, 3566.0, 3566.892993210156, 3567.3935430976794, 6806.319368063154, 6807.149106361705, 6817.829554261084, 6821.658917054127, 6821.69302381394, 6726.7981960541265, 6733.865322693617, 6823.692933817891, 6824.0446337512785, 6826.93173068263, 6583.0, 6713.966430167916, 6717.764878229336, 6881.65591720412, 6901.654917254117, 6508.576942498722, 6612.371824676648, 6641.0, 6647.767328143492, 6648.767293144717, 6515.967420162869, 6541.542092053511, 6548.8362790930605, 6551.115599394021, 6560.737570497126, 6733.293004234681, 6872.209695885037, 6873.312668733087, 6874.0, 6881.690323935335, 4392.472903251631, 4421.734695918255, 4424.469063712375, 4424.823007079681, 4428.867133985986, 6609.834754130993, 6611.603303801788, 6618.2719900811835, 6620.0, 6626.900596491153, 6297.307296197323, 6423.357664233539, 6437.0, 6442.71145770857, 6444.13004244421, 6377.489800816016, 6391.616503009835, 6393.392627700361, 6393.584417012879, 6394.840128996626, 6189.319174890668, 6205.47253483459, 6220.782272620618, 6227.408396202353, 6229.283632382334, 6305.779297724557, 6435.388638079375, 6436.259830119596, 6438.935610644021, 6458.515611329263, 6282.591631543935, 6302.495800335871, 6303.243610766737, 6308.495320374451, 6310.5267104968225], "unit": "ms", "name": "prototype.html", "value": 4784.955641325919}, {"lowerIsBetter": false, "replicates": [749.1197842535022, 819.6352623082729, 834.1033389106708, 838.9370797190209, 866.900306464756, 12736.344275244217, 12961.157524760883, 13010.479580816767, 13011.219326840372, 13011.73976520469, 12117.575884844033, 12194.81707774384, 12199.939000305008, 12204.572839950604, 12205.328706921116, 669.7655820462836, 683.0846665468272, 812.5490352854164, 822.9300509456704, 827.6482494939656, 811.0915774332726, 813.1868131868132, 813.2843098073687, 813.6663967773214, 815.0993152566417, 707.4941416886925, 853.9530325832077, 854.0647990450478, 856.4390324337569, 856.4732689396008, 715.3418854653705, 851.0213254757017, 868.2880038368477, 869.1656010230187, 870.2646263906988, 628.7296462521114, 630.8076036808772, 632.0172132334219, 632.5572099530301, 632.7690393006519, 12284.938575307156, 12320.5071797128, 12331.691707707289, 12361.134720569564, 12416.565420210249, 11957.940210298893, 12229.449674764657, 12315.93842030793, 12562.748745025048, 12576.182548134342, 9855.162311203556, 9924.652637157666, 10200.744981375525, 10462.843057354146, 10755.516001779939, 3508.5438892943753, 3553.07620018797, 3608.548931383577, 3635.618260082706, 3696.907577310562, 47760.0, 47823.282650760266, 47884.0, 48055.51944480545, 49294.50705492935, 9041.593128309241, 9067.909320906838, 9101.40840845343, 9257.768555786024, 9360.238729574792, 17997.0, 18020.819791801914, 18044.639107218045, 18210.908945455187, 18272.360467383845, 7696.268854458817, 7827.804304892309, 7866.370690344758, 7873.212678732196, 7928.326092282221, 4345.391645169679, 4346.782660866944, 4364.236258654722, 4414.139242847678, 4429.512753597101, 2996.161074899032, 3026.7881248312406, 3035.089473158044, 3043.269615292314, 3045.771567132474, 561.1834780394598, 565.8968029798684, 566.1620801214226, 570.597728601335, 571.5713215088683, 614.620177701064, 628.9182406287154, 635.1774452084469, 644.3138057968249, 727.7380143148463, 8211.384146189059, 8289.958550207331, 8314.0, 8346.4157508975, 8367.414281000272, 477.8661974647104, 619.8264485943943, 653.1345966594187, 674.6694119881223, 680.9285025072395, 4062.1672557125717, 4138.317177665712, 4150.190712811033, 4217.304144816071, 4339.696221264481, 55965.44034559602, 56868.14697779537, 57226.0, 57523.0, 57954.42045579575, 49960.25059624109, 51803.74098129485, 51822.22266666003, 52042.73978630082, 52184.47815521796, 45021.77489112599, 45114.77442612766, 45240.0, 46094.53905460903, 46667.0, 8232.382571307056, 8734.868976965477, 8805.69180078694, 8895.555222238732, 9100.453972761656, 32173.839130804197, 32308.19229519281, 33062.83468582642, 33746.66253337435, 34133.146671333416, 43856.56143438652, 44161.779191105124, 44403.0, 44637.33044004277, 45477.77261113673, 10903.34579925207, 10915.836262456229, 11006.724831879266, 11070.391128487689, 11137.832932505857, 12383.380830958597, 12516.12387132892, 12545.310007949285, 12659.131823388889, 12786.232826030468, 10918.454077296263, 10978.231523793258, 11233.269837460539, 11237.943810280896, 11597.76804463923, 11041.282316649393, 11203.375510551088, 11374.374409407232, 11761.000314973324, 11770.0, 10823.296485728402, 11171.441427928408, 11172.497237624164, 11295.7740845181, 11514.136439766884, 9463.242940564876, 9750.463724495221, 9843.409395436296, 10018.0, 10395.636152734618, 9200.815983680423, 9248.861267081134, 9313.627454901729, 9330.440173589335, 9462.858057129286], "unit": "ms", "name": "yui.html", "value": 6236.617679643078}], "name": "dromaeo_css", "value": 6965.314708845993}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 106.0, 111.0, 111.0, 112.0, 101.0, 110.0, 109.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 109.0}, {"lowerIsBetter": true, "replicates": [151.0, 124.0, 148.0, 167.0, 125.0, 156.0, 150.0, 144.0, 168.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.7}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 161.0, 162.0, 161.0, 161.0, 160.0, 162.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 89.0, 96.0, 72.0, 72.0, 72.0, 105.0, 97.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 81.8}, {"lowerIsBetter": true, "replicates": [91.0, 91.0, 135.0, 91.0, 91.0, 91.0, 136.0, 89.0, 90.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 104.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.0}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 59.0, 59.0, 59.0, 58.0, 60.0, 66.0, 58.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 59.1}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 69.0, 69.0, 69.0, 69.0, 70.0, 70.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [86.0, 94.0, 86.0, 85.0, 93.0, 85.0, 86.0, 84.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.9}, {"lowerIsBetter": true, "replicates": [120.0, 118.0, 117.0, 114.0, 123.0, 116.0, 124.0, 115.0, 114.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.5}, {"lowerIsBetter": true, "replicates": [141.0, 150.0, 163.0, 141.0, 149.0, 139.0, 160.0, 159.0, 146.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.0}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 65.0, 66.0, 67.0, 67.0, 67.0, 67.0, 68.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1499.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.07893020221786], "unit": "score", "name": "Richards", "value": 216.47186399999995}, {"lowerIsBetter": false, "replicates": [193.87359441644048], "unit": "score", "name": "DeltaBlue", "value": 341.0366439999999}, {"lowerIsBetter": false, "replicates": [284.4950213371266], "unit": "score", "name": "Encrypt", "value": 935.6262149999995}, {"lowerIsBetter": false, "replicates": [5030.150753768844], "unit": "score", "name": "Decrypt", "value": 52.91710189810189}, {"lowerIsBetter": false, "replicates": [1095.290251916758], "unit": "score", "name": "RayTrace", "value": 675.6099569999998}, {"lowerIsBetter": false, "replicates": [778.816199376947], "unit": "score", "name": "Earley", "value": 855.7384919999998}, {"lowerIsBetter": false, "replicates": [12108.43373493976], "unit": "score", "name": "Boyer", "value": 55.0412228855721}, {"lowerIsBetter": false, "replicates": [26342.105263157893], "unit": "score", "name": "RegExp", "value": 34.58284715284714}, {"lowerIsBetter": false, "replicates": [593.1198102016607], "unit": "score", "name": "Splay", "value": 137.39382600000002}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 21122.640026881207}]} 01:53:39 INFO - RETURN: dromaeo_css: 6965.32 01:53:39 INFO - RETURN: kraken: 1499.40 01:53:40 INFO - RETURN: v8_7: 21122.64 01:53:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "21122.64"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1499.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6965.32"}}} 01:53:40 INFO - Return code: 0 01:53:40 INFO - # TBPL SUCCESS # 01:53:40 INFO - Running post-action listener: _resource_record_post_action 01:53:40 INFO - Running post-run listener: _resource_record_post_run 01:53:41 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 511918080; Read time: 208; Write time: 527360 01:53:41 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 59117568; Read time: 0; Write time: 39200 01:53:41 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 374751232; Read time: 208; Write time: 382380 01:53:41 INFO - Running post-run listener: _upload_blobber_files 01:53:41 INFO - Blob upload gear active. 01:53:41 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:53:41 INFO - Copying logs to upload dir... 01:53:41 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.096871 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-11-20 01:53:40.960974) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 01:53:40.964452) ========= 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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010038 build_url: 'https://queue.taskcluster.net/v1/task/LJzXHVDxTAynr1kwWevkyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 01:53:41.014981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:53:41.015322) ========= 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=7d909545e45784438f4e8e36000001b3-1448011492.125616-694772910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004543 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 01:53:41.065146) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 01:53:41.065479) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 01:53:41.065796) ========= ========= Total master_lag: 0.24 =========