builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1448063988.81 results: success (0) buildid: 20151120143006 builduid: 3e002eb0799948ec8ceef1f2cc90cba2 revision: b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.811188) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.811686) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.811994) ========= 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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006643 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.833168) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.833439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.854155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.854441) ========= 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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.904529) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.904867) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.905309) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:48.905595) ========= 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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-20 15:59:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 64.1M=0s 2015-11-20 15:59:49 (64.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.177036 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.094916) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.095229) ========= 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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013755 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.144138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.144473) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 --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 b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 --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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-20 15:59:49,929 truncating revision to first 12 chars 2015-11-20 15:59:49,929 Setting DEBUG logging. 2015-11-20 15:59:49,929 attempt 1/10 2015-11-20 15:59:49,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b0bdf1bc3b61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-20 15:59:50,163 unpacking tar archive at: fx-team-b0bdf1bc3b61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.506394 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.663343) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.663651) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.671964) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.672214) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:49.672594) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-11-20 15:59:49.672894) ========= /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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:59:50 INFO - MultiFileLogger online at 20151120 15:59:50 in /builds/slave/test-pgo 15:59:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:59:50 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:59:50 INFO - {'append_to_log': False, 15:59:50 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:59:50 INFO - 'blob_upload_branch': 'Fx-Team', 15:59:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:59:50 INFO - 'branch': 'Fx-Team', 15:59:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:50 INFO - 'config_files': ('talos/linux_config.py',), 15:59:50 INFO - 'default_actions': ('clobber', 15:59:50 INFO - 'read-buildbot-config', 15:59:50 INFO - 'download-and-extract', 15:59:50 INFO - 'populate-webroot', 15:59:50 INFO - 'create-virtualenv', 15:59:50 INFO - 'install', 15:59:50 INFO - 'run-tests'), 15:59:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:50 INFO - 'download_minidump_stackwalk': True, 15:59:50 INFO - 'download_symbols': 'ondemand', 15:59:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:59:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:59:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:59:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:59:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:50 INFO - 'installer_path': 'installer.exe', 15:59:50 INFO - 'log_level': 'info', 15:59:50 INFO - 'log_name': 'talos', 15:59:50 INFO - 'log_to_console': True, 15:59:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:59:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:59:50 INFO - 'opt_config_files': (), 15:59:50 INFO - 'pip_index': False, 15:59:50 INFO - 'sps_profile': False, 15:59:50 INFO - 'sps_profile_interval': 0, 15:59:50 INFO - 'suite': 'dromaeojs', 15:59:50 INFO - 'system_bits': '64', 15:59:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:50 INFO - 'title': 'talos-linux64-ix-066', 15:59:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:50 INFO - 'use_talos_json': True, 15:59:50 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:59:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:50 INFO - 'work_dir': 'build'} 15:59:50 INFO - ##### 15:59:50 INFO - ##### Running clobber step. 15:59:50 INFO - ##### 15:59:50 INFO - Running pre-action listener: _resource_record_pre_action 15:59:50 INFO - Running main action method: clobber 15:59:50 INFO - rmtree: /builds/slave/test-pgo/build 15:59:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:59:51 INFO - Running post-action listener: _resource_record_post_action 15:59:51 INFO - ##### 15:59:51 INFO - ##### Running read-buildbot-config step. 15:59:51 INFO - ##### 15:59:51 INFO - Running pre-action listener: _resource_record_pre_action 15:59:51 INFO - Running main action method: read_buildbot_config 15:59:51 INFO - Using buildbot properties: 15:59:51 INFO - { 15:59:51 INFO - "properties": { 15:59:51 INFO - "buildnumber": 505, 15:59:51 INFO - "product": "firefox", 15:59:51 INFO - "script_repo_revision": "production", 15:59:51 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:59:51 INFO - "repository": "", 15:59:51 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 15:59:51 INFO - "buildid": "20151120143006", 15:59:51 INFO - "slavename": "talos-linux64-ix-066", 15:59:51 INFO - "pgo_build": "True", 15:59:51 INFO - "basedir": "/builds/slave/test-pgo", 15:59:51 INFO - "project": "", 15:59:51 INFO - "platform": "ubuntu64_hw", 15:59:51 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:59:51 INFO - "slavebuilddir": "test", 15:59:51 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 15:59:51 INFO - "branch": "fx-team", 15:59:51 INFO - "repo_path": "integration/fx-team", 15:59:51 INFO - "stage_platform": "linux64-pgo", 15:59:51 INFO - "builduid": "3e002eb0799948ec8ceef1f2cc90cba2", 15:59:51 INFO - "revision": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73" 15:59:51 INFO - }, 15:59:51 INFO - "sourcestamp": { 15:59:51 INFO - "repository": "", 15:59:51 INFO - "hasPatch": false, 15:59:51 INFO - "project": "", 15:59:51 INFO - "branch": "fx-team-linux64-pgo-talos", 15:59:51 INFO - "changes": [ 15:59:51 INFO - { 15:59:51 INFO - "category": null, 15:59:51 INFO - "files": [ 15:59:51 INFO - { 15:59:51 INFO - "url": null, 15:59:51 INFO - "name": "https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 15:59:51 INFO - } 15:59:51 INFO - ], 15:59:51 INFO - "repository": "", 15:59:51 INFO - "rev": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73", 15:59:51 INFO - "who": "sendchange", 15:59:51 INFO - "when": 1448063911, 15:59:51 INFO - "number": 6691183, 15:59:51 INFO - "comments": "", 15:59:51 INFO - "project": "", 15:59:51 INFO - "at": "Fri 20 Nov 2015 15:58:31", 15:59:51 INFO - "branch": "fx-team-linux64-pgo-talos", 15:59:51 INFO - "revlink": "", 15:59:51 INFO - "properties": [ 15:59:51 INFO - [ 15:59:51 INFO - "buildid", 15:59:51 INFO - "20151120143006", 15:59:51 INFO - "Change" 15:59:51 INFO - ], 15:59:51 INFO - [ 15:59:51 INFO - "builduid", 15:59:51 INFO - "3e002eb0799948ec8ceef1f2cc90cba2", 15:59:51 INFO - "Change" 15:59:51 INFO - ], 15:59:51 INFO - [ 15:59:51 INFO - "pgo_build", 15:59:51 INFO - "True", 15:59:51 INFO - "Change" 15:59:51 INFO - ] 15:59:51 INFO - ], 15:59:51 INFO - "revision": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73" 15:59:51 INFO - } 15:59:51 INFO - ], 15:59:51 INFO - "revision": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73" 15:59:51 INFO - } 15:59:51 INFO - } 15:59:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 15:59:51 INFO - Running post-action listener: _resource_record_post_action 15:59:51 INFO - ##### 15:59:51 INFO - ##### Running download-and-extract step. 15:59:51 INFO - ##### 15:59:51 INFO - Running pre-action listener: _resource_record_pre_action 15:59:51 INFO - Running main action method: download_and_extract 15:59:51 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:59: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')]} 15:59:51 INFO - https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:59:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json 15:59:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json 15:59:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:59:52 INFO - Downloaded 1302 bytes. 15:59:52 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:59:52 INFO - Using the following test package requirements: 15:59:52 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:52 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:59:52 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'jsshell-linux-x86_64.zip'], 15:59:52 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:59:52 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:52 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:59:52 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:59:52 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:59:52 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:52 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:59:52 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:52 INFO - https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:59:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:59:53 INFO - Downloaded 22525382 bytes. 15:59:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:59:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:59:54 INFO - Return code: 0 15:59:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:54 INFO - https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:59:55 INFO - Downloaded 22525382 bytes. 15:59:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:59:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:59:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:59:56 INFO - Return code: 0 15:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:56 INFO - https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:59:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:59:58 INFO - Downloaded 11130997 bytes. 15:59:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 15:59:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 15:59:58 INFO - Return code: 0 15:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:59:58 INFO - https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:59:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:59:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:59:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/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 16:00:00 INFO - Downloaded 57351284 bytes. 16:00:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:00:00 INFO - mkdir: /builds/slave/test-pgo/properties 16:00:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:00:00 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:00:00 INFO - Contents: 16:00:00 INFO - build_url:https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:00:00 INFO - Running post-action listener: _resource_record_post_action 16:00:00 INFO - Running post-action listener: set_extra_try_arguments 16:00:00 INFO - ##### 16:00:00 INFO - ##### Running populate-webroot step. 16:00:00 INFO - ##### 16:00:00 INFO - Running pre-action listener: _resource_record_pre_action 16:00:00 INFO - Running main action method: populate_webroot 16:00:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:00 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:00:00 INFO - '../fennec_ids.txt'], 16:00:00 INFO - 'tests': ['tcheck2']}, 16:00:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:00 INFO - '--tppagecycles', 16:00:00 INFO - '7'], 16:00:00 INFO - 'tests': ['tsvgm']}}, 16:00:00 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:00:00 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:00 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:00:00 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:00:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'tests': ['damp', 'tps']}, 16:00:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['damp', 'tps']}, 16:00:00 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:00:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:00 INFO - 'other': {'tests': ['a11yr', 16:00:00 INFO - 'ts_paint', 16:00:00 INFO - 'tpaint', 16:00:00 INFO - 'sessionrestore', 16:00:00 INFO - 'sessionrestore_no_auto_restore']}, 16:00:00 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['a11yr', 16:00:00 INFO - 'ts_paint', 16:00:00 INFO - 'tpaint', 16:00:00 INFO - 'sessionrestore', 16:00:00 INFO - 'sessionrestore_no_auto_restore']}, 16:00:00 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['a11yr', 16:00:00 INFO - 'ts_paint', 16:00:00 INFO - 'tpaint', 16:00:00 INFO - 'sessionrestore', 16:00:00 INFO - 'sessionrestore_no_auto_restore']}, 16:00:00 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['a11yr', 16:00:00 INFO - 'ts_paint', 16:00:00 INFO - 'tpaint', 16:00:00 INFO - 'sessionrestore', 16:00:00 INFO - 'sessionrestore_no_auto_restore']}, 16:00:00 INFO - 'other_l64': {'tests': ['a11yr', 16:00:00 INFO - 'ts_paint', 16:00:00 INFO - 'tpaint', 16:00:00 INFO - 'sessionrestore', 16:00:00 INFO - 'sessionrestore_no_auto_restore']}, 16:00:00 INFO - 'other_nol64': {'tests': ['a11yr', 16:00:00 INFO - 'ts_paint', 16:00:00 INFO - 'tpaint', 16:00:00 INFO - 'sessionrestore', 16:00:00 INFO - 'sessionrestore_no_auto_restore']}, 16:00:00 INFO - 'svgr': {'tests': ['tsvgx', 16:00:00 INFO - 'tsvgr_opacity', 16:00:00 INFO - 'tart', 16:00:00 INFO - 'tscrollx', 16:00:00 INFO - 'cart']}, 16:00:00 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['tsvgx', 16:00:00 INFO - 'tsvgr_opacity', 16:00:00 INFO - 'tart', 16:00:00 INFO - 'tscrollx', 16:00:00 INFO - 'cart']}, 16:00:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'tests': ['tp5o']}, 16:00:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'talos_options': ['--e10s'], 16:00:00 INFO - 'tests': ['tp5o']}, 16:00:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'talos_options': ['--xperf_path', 16:00:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:00 INFO - 'tests': ['tp5n']}, 16:00:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:00 INFO - 'talos_options': ['--e10s', 16:00:00 INFO - '--xperf_path', 16:00:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:00 INFO - 'tests': ['tp5n']}}, 16:00:00 INFO - 'talos.zip': {'path': '', 16:00:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:00 INFO - Running post-action listener: _resource_record_post_action 16:00:00 INFO - ##### 16:00:00 INFO - ##### Running create-virtualenv step. 16:00:00 INFO - ##### 16:00:00 INFO - Running pre-action listener: _resource_record_pre_action 16:00:00 INFO - Running main action method: create_virtualenv 16:00:00 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:00:00 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 16:00:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:00:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:00 INFO - Using real prefix '/usr' 16:00:00 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:00:00 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:02 INFO - Installing pip.................done. 16:00:02 INFO - Return code: 0 16:00:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:00:02 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')]} 16:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:02 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')]} 16:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:02 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:02 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 16:00:02 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 16:00:02 INFO - Using env: {'DISPLAY': ':0', 16:00:02 INFO - 'HOME': '/home/cltbld', 16:00:02 INFO - 'LANG': 'en_US.UTF-8', 16:00:02 INFO - 'LANGUAGE': 'en_US:en', 16:00:02 INFO - 'LOGNAME': 'cltbld', 16:00:02 INFO - 'MAIL': '/var/mail/cltbld', 16:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:02 INFO - 'MOZ_NO_REMOTE': '1', 16:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:02 INFO - 'NO_EM_RESTART': '1', 16:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:02 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:02 INFO - 'SHELL': '/bin/bash', 16:00:02 INFO - 'SHLVL': '1', 16:00:02 INFO - 'TERM': 'linux', 16:00:02 INFO - 'TMOUT': '86400', 16:00:02 INFO - 'USER': 'cltbld', 16:00:02 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:02 INFO - '_': '/tools/buildbot/bin/python'} 16:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:02 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 16:00:02 INFO - Cleaning up... 16:00:02 INFO - Return code: 0 16:00:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:00:02 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')]} 16:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:02 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')]} 16:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:02 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:02 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 16:00:02 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 16:00:03 INFO - Using env: {'DISPLAY': ':0', 16:00:03 INFO - 'HOME': '/home/cltbld', 16:00:03 INFO - 'LANG': 'en_US.UTF-8', 16:00:03 INFO - 'LANGUAGE': 'en_US:en', 16:00:03 INFO - 'LOGNAME': 'cltbld', 16:00:03 INFO - 'MAIL': '/var/mail/cltbld', 16:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:03 INFO - 'MOZ_NO_REMOTE': '1', 16:00:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:03 INFO - 'NO_EM_RESTART': '1', 16:00:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:03 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:03 INFO - 'SHELL': '/bin/bash', 16:00:03 INFO - 'SHLVL': '1', 16:00:03 INFO - 'TERM': 'linux', 16:00:03 INFO - 'TMOUT': '86400', 16:00:03 INFO - 'USER': 'cltbld', 16:00:03 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:03 INFO - '_': '/tools/buildbot/bin/python'} 16:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:03 INFO - Downloading/unpacking psutil>=0.7.1 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:04 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:00:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:00:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:05 INFO - Installing collected packages: psutil 16:00:05 INFO - Running setup.py install for psutil 16:00:05 INFO - building 'psutil._psutil_linux' extension 16:00:05 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 16:00:05 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 16:00:05 INFO - building 'psutil._psutil_posix' extension 16:00:05 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 16:00:05 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 16:00:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:05 INFO - Successfully installed psutil 16:00:05 INFO - Cleaning up... 16:00:05 INFO - Return code: 0 16:00:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:00: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')]} 16:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:05 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:05 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 16:00:05 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 16:00:05 INFO - Using env: {'DISPLAY': ':0', 16:00:05 INFO - 'HOME': '/home/cltbld', 16:00:05 INFO - 'LANG': 'en_US.UTF-8', 16:00:05 INFO - 'LANGUAGE': 'en_US:en', 16:00:05 INFO - 'LOGNAME': 'cltbld', 16:00:05 INFO - 'MAIL': '/var/mail/cltbld', 16:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:05 INFO - 'MOZ_NO_REMOTE': '1', 16:00:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:05 INFO - 'NO_EM_RESTART': '1', 16:00:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:05 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:05 INFO - 'SHELL': '/bin/bash', 16:00:05 INFO - 'SHLVL': '1', 16:00:05 INFO - 'TERM': 'linux', 16:00:05 INFO - 'TMOUT': '86400', 16:00:05 INFO - 'USER': 'cltbld', 16:00:05 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:05 INFO - '_': '/tools/buildbot/bin/python'} 16:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:00:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:00:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:00:07 INFO - Installing collected packages: mozsystemmonitor 16:00:07 INFO - Running setup.py install for mozsystemmonitor 16:00:07 INFO - Successfully installed mozsystemmonitor 16:00:07 INFO - Cleaning up... 16:00:07 INFO - Return code: 0 16:00:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:00:07 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')]} 16:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:07 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')]} 16:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:07 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:07 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 16:00:07 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 16:00:07 INFO - Using env: {'DISPLAY': ':0', 16:00:07 INFO - 'HOME': '/home/cltbld', 16:00:07 INFO - 'LANG': 'en_US.UTF-8', 16:00:07 INFO - 'LANGUAGE': 'en_US:en', 16:00:07 INFO - 'LOGNAME': 'cltbld', 16:00:07 INFO - 'MAIL': '/var/mail/cltbld', 16:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:07 INFO - 'MOZ_NO_REMOTE': '1', 16:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:07 INFO - 'NO_EM_RESTART': '1', 16:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:07 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:07 INFO - 'SHELL': '/bin/bash', 16:00:07 INFO - 'SHLVL': '1', 16:00:07 INFO - 'TERM': 'linux', 16:00:07 INFO - 'TMOUT': '86400', 16:00:07 INFO - 'USER': 'cltbld', 16:00:07 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:07 INFO - '_': '/tools/buildbot/bin/python'} 16:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:07 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:07 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 16:00:07 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 16:00:07 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 16:00:07 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 16:00:07 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 16:00:07 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 16:00:09 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:00:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:00:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:00:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:10 INFO - Downloading docopt-0.6.1.tar.gz 16:00:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:00:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:10 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:10 INFO - Running setup.py install for blobuploader 16:00:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:10 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:00:10 INFO - Running setup.py install for requests 16:00:10 INFO - Running setup.py install for docopt 16:00:10 INFO - Successfully installed blobuploader requests docopt 16:00:10 INFO - Cleaning up... 16:00:10 INFO - Return code: 0 16:00:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:10 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:10 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 16:00:10 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 16:00:10 INFO - Using env: {'DISPLAY': ':0', 16:00:10 INFO - 'HOME': '/home/cltbld', 16:00:10 INFO - 'LANG': 'en_US.UTF-8', 16:00:10 INFO - 'LANGUAGE': 'en_US:en', 16:00:10 INFO - 'LOGNAME': 'cltbld', 16:00:10 INFO - 'MAIL': '/var/mail/cltbld', 16:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:10 INFO - 'MOZ_NO_REMOTE': '1', 16:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:10 INFO - 'NO_EM_RESTART': '1', 16:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:10 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:10 INFO - 'SHELL': '/bin/bash', 16:00:10 INFO - 'SHLVL': '1', 16:00:10 INFO - 'TERM': 'linux', 16:00:10 INFO - 'TMOUT': '86400', 16:00:10 INFO - 'USER': 'cltbld', 16:00:10 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:10 INFO - '_': '/tools/buildbot/bin/python'} 16:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:11 INFO - Running setup.py (path:/tmp/pip-ovXOGI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:11 INFO - Running setup.py (path:/tmp/pip-oZkzCT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:11 INFO - Running setup.py (path:/tmp/pip-i_MoV2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:11 INFO - Running setup.py (path:/tmp/pip-0wmcE3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:11 INFO - Running setup.py (path:/tmp/pip-DSMT51-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:11 INFO - Running setup.py (path:/tmp/pip-QLQxsT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:11 INFO - Running setup.py (path:/tmp/pip-NxXQtz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:11 INFO - Running setup.py (path:/tmp/pip-rUprOS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:11 INFO - Running setup.py (path:/tmp/pip-fIgLiN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:11 INFO - Running setup.py (path:/tmp/pip-OksoUf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:11 INFO - Running setup.py (path:/tmp/pip-xRhjhW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:11 INFO - Running setup.py (path:/tmp/pip-t_jEkD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:11 INFO - Running setup.py (path:/tmp/pip-j4i7zC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:11 INFO - Running setup.py (path:/tmp/pip-LySxDV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:12 INFO - Running setup.py (path:/tmp/pip-cGd6_S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:12 INFO - Running setup.py (path:/tmp/pip-GQsyZ5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:12 INFO - Running setup.py (path:/tmp/pip-KKsDc0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:12 INFO - Running setup.py install for manifestparser 16:00:12 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:00:12 INFO - Running setup.py install for mozcrash 16:00:12 INFO - Running setup.py install for mozdebug 16:00:12 INFO - Running setup.py install for mozdevice 16:00:12 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:00:12 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:00:12 INFO - Running setup.py install for mozfile 16:00:12 INFO - Running setup.py install for mozhttpd 16:00:12 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:00:12 INFO - Running setup.py install for mozinfo 16:00:12 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:00:12 INFO - Running setup.py install for mozInstall 16:00:13 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Running setup.py install for mozleak 16:00:13 INFO - Running setup.py install for mozlog 16:00:13 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Running setup.py install for moznetwork 16:00:13 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Running setup.py install for mozprocess 16:00:13 INFO - Running setup.py install for mozprofile 16:00:13 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Running setup.py install for mozrunner 16:00:13 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:00:13 INFO - Running setup.py install for mozscreenshot 16:00:13 INFO - Running setup.py install for moztest 16:00:13 INFO - Running setup.py install for mozversion 16:00:14 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:00:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:14 INFO - Cleaning up... 16:00:14 INFO - Return code: 0 16:00:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:14 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')]} 16:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:14 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')]} 16:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:14 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:14 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 16:00:14 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 16:00:14 INFO - Using env: {'DISPLAY': ':0', 16:00:14 INFO - 'HOME': '/home/cltbld', 16:00:14 INFO - 'LANG': 'en_US.UTF-8', 16:00:14 INFO - 'LANGUAGE': 'en_US:en', 16:00:14 INFO - 'LOGNAME': 'cltbld', 16:00:14 INFO - 'MAIL': '/var/mail/cltbld', 16:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:14 INFO - 'MOZ_NO_REMOTE': '1', 16:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:14 INFO - 'NO_EM_RESTART': '1', 16:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:14 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:14 INFO - 'SHELL': '/bin/bash', 16:00:14 INFO - 'SHLVL': '1', 16:00:14 INFO - 'TERM': 'linux', 16:00:14 INFO - 'TMOUT': '86400', 16:00:14 INFO - 'USER': 'cltbld', 16:00:14 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:14 INFO - '_': '/tools/buildbot/bin/python'} 16:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:14 INFO - Running setup.py (path:/tmp/pip-G3M2tA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:14 INFO - Running setup.py (path:/tmp/pip-wwsXx5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:14 INFO - Running setup.py (path:/tmp/pip-O9_J6x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:14 INFO - Running setup.py (path:/tmp/pip-Il5JjK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:14 INFO - Running setup.py (path:/tmp/pip-4efVMF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:14 INFO - Running setup.py (path:/tmp/pip-ntr_3B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:14 INFO - Running setup.py (path:/tmp/pip-JRQ7G5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:14 INFO - Running setup.py (path:/tmp/pip-P8qPIh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:14 INFO - Running setup.py (path:/tmp/pip-QpfR6A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:14 INFO - Running setup.py (path:/tmp/pip-ydkiVg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:14 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)) 16:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:14 INFO - Running setup.py (path:/tmp/pip-KuGlHy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:15 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)) 16:00:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:15 INFO - Running setup.py (path:/tmp/pip-fXU6f0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:15 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)) 16:00:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:15 INFO - Running setup.py (path:/tmp/pip-tOHsXn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:15 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)) 16:00:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:15 INFO - Running setup.py (path:/tmp/pip-EI2S25-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:15 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)) 16:00:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:15 INFO - Running setup.py (path:/tmp/pip-QsV580-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:15 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)) 16:00:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:15 INFO - Running setup.py (path:/tmp/pip-2LXd7V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:15 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)) 16:00:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:15 INFO - Running setup.py (path:/tmp/pip-iEiJMk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:15 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)) 16:00:15 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)) 16:00:15 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)) 16:00:15 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)) 16:00:15 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)) 16:00:15 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)) 16:00:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:17 INFO - Downloading blessings-1.5.1.tar.gz 16:00:17 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 16:00:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:17 INFO - Installing collected packages: blessings 16:00:17 INFO - Running setup.py install for blessings 16:00:17 INFO - Successfully installed blessings 16:00:17 INFO - Cleaning up... 16:00:17 INFO - Return code: 0 16:00:17 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:00:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:00:17 INFO - Reading from file tmpfile_stdout 16:00:17 INFO - Current package versions: 16:00:17 INFO - argparse == 1.2.1 16:00:17 INFO - blessings == 1.5.1 16:00:17 INFO - blobuploader == 1.2.4 16:00:17 INFO - docopt == 0.6.1 16:00:17 INFO - manifestparser == 1.1 16:00:17 INFO - mozInstall == 1.12 16:00:17 INFO - mozcrash == 0.16 16:00:17 INFO - mozdebug == 0.1 16:00:17 INFO - mozdevice == 0.47 16:00:17 INFO - mozfile == 1.2 16:00:17 INFO - mozhttpd == 0.7 16:00:17 INFO - mozinfo == 0.9 16:00:17 INFO - mozleak == 0.1 16:00:17 INFO - mozlog == 3.0 16:00:17 INFO - moznetwork == 0.27 16:00:17 INFO - mozprocess == 0.22 16:00:17 INFO - mozprofile == 0.27 16:00:17 INFO - mozrunner == 6.11 16:00:17 INFO - mozscreenshot == 0.1 16:00:17 INFO - mozsystemmonitor == 0.0 16:00:17 INFO - moztest == 0.7 16:00:17 INFO - mozversion == 1.4 16:00:17 INFO - psutil == 3.1.1 16:00:17 INFO - requests == 1.2.3 16:00:17 INFO - wsgiref == 0.1.2 16:00:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:17 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 0x7f5c5c6efc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25676c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26197c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', '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 16:00:17 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 16:00:17 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 16:00:17 INFO - Using env: {'DISPLAY': ':0', 16:00:17 INFO - 'HOME': '/home/cltbld', 16:00:17 INFO - 'LANG': 'en_US.UTF-8', 16:00:17 INFO - 'LANGUAGE': 'en_US:en', 16:00:17 INFO - 'LOGNAME': 'cltbld', 16:00:17 INFO - 'MAIL': '/var/mail/cltbld', 16:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:17 INFO - 'MOZ_NO_REMOTE': '1', 16:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:17 INFO - 'NO_EM_RESTART': '1', 16:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:17 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:17 INFO - 'SHELL': '/bin/bash', 16:00:17 INFO - 'SHLVL': '1', 16:00:17 INFO - 'TERM': 'linux', 16:00:17 INFO - 'TMOUT': '86400', 16:00:17 INFO - 'USER': 'cltbld', 16:00:17 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:17 INFO - '_': '/tools/buildbot/bin/python'} 16:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 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)) 16:00:17 INFO - Cleaning up... 16:00:17 INFO - Return code: 0 16:00:17 INFO - Running post-action listener: _resource_record_post_action 16:00:17 INFO - Running post-action listener: _start_resource_monitoring 16:00:17 INFO - Starting resource monitoring. 16:00:17 INFO - ##### 16:00:17 INFO - ##### Running install step. 16:00:17 INFO - ##### 16:00:17 INFO - Running pre-action listener: _resource_record_pre_action 16:00:17 INFO - Running main action method: install 16:00:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:00:18 INFO - Reading from file tmpfile_stdout 16:00:18 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:00:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:00:18 INFO - Reading from file tmpfile_stdout 16:00:18 INFO - Output received: 16:00:18 INFO - Usage: mozinstall [options] installer 16:00:18 INFO - Options: 16:00:18 INFO - -h, --help show this help message and exit 16:00:18 INFO - -d DEST, --destination=DEST 16:00:18 INFO - Directory to install application into. [default: 16:00:18 INFO - "/builds/slave/test-pgo"] 16:00:18 INFO - --app=APP Application being installed. [default: firefox] 16:00:18 INFO - mkdir: /builds/slave/test-pgo/build/application 16:00:18 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'] 16:00:18 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 16:00:32 INFO - Reading from file tmpfile_stdout 16:00:32 INFO - Output received: 16:00:32 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:00:32 INFO - Running post-action listener: _resource_record_post_action 16:00:32 INFO - ##### 16:00:32 INFO - ##### Running run-tests step. 16:00:32 INFO - ##### 16:00:32 INFO - Running pre-action listener: _resource_record_pre_action 16:00:32 INFO - Running main action method: run_tests 16:00:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:00:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:00:32 INFO - Python 2.7.3 16:00:32 INFO - Return code: 0 16:00:32 INFO - grabbing minidump binary from tooltool 16:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:32 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 0x265dd70>, '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 0x265eb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x265efb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:00:32 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 16:00:32 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 16:00:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:32 INFO - Return code: 0 16:00:32 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:00:32 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:00:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:00:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:00:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:00:32 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/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:00:32 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/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 16:00:32 INFO - Using env: {'DISPLAY': ':0', 16:00:32 INFO - 'HOME': '/home/cltbld', 16:00:32 INFO - 'LANG': 'en_US.UTF-8', 16:00:32 INFO - 'LANGUAGE': 'en_US:en', 16:00:32 INFO - 'LOGNAME': 'cltbld', 16:00:32 INFO - 'MAIL': '/var/mail/cltbld', 16:00:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:00:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:32 INFO - 'MOZ_NO_REMOTE': '1', 16:00:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:32 INFO - 'NO_EM_RESTART': '1', 16:00:32 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:32 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:32 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:00:32 INFO - 'SHELL': '/bin/bash', 16:00:32 INFO - 'SHLVL': '1', 16:00:32 INFO - 'TERM': 'linux', 16:00:32 INFO - 'TMOUT': '86400', 16:00:32 INFO - 'USER': 'cltbld', 16:00:32 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439', 16:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:32 INFO - '_': '/tools/buildbot/bin/python'} 16:00:32 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/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 16:00:32 INFO - mozversion INFO | application_buildid: 20151120143006 16:00:32 INFO - mozversion INFO | application_changeset: b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 16:00:32 INFO - mozversion INFO | application_display_name: Nightly 16:00:32 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:32 INFO - mozversion INFO | application_name: Firefox 16:00:32 INFO - mozversion INFO | application_remotingname: firefox 16:00:32 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 16:00:32 INFO - mozversion INFO | application_vendor: Mozilla 16:00:32 INFO - mozversion INFO | application_version: 45.0a1 16:00:32 INFO - mozversion INFO | platform_buildid: 20151120143006 16:00:32 INFO - mozversion INFO | platform_changeset: b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 16:00:32 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 16:00:32 INFO - mozversion INFO | platform_version: 45.0a1 16:00:32 INFO - 2015-11-20 16:00:32,802 DEBUG : using testdate: 1448064032 16:00:32 INFO - 2015-11-20 16:00:32,802 DEBUG : actual date: 1448064032 16:00:32 INFO - 2015-11-20 16:00:32,810 INFO : starting webserver on 'localhost:55635' 16:00:32 INFO - 2015-11-20 16:00:32,811 INFO : Starting test suite talos-linux64-ix-066 16:00:32 INFO - 2015-11-20 16:00:32,811 INFO : Starting test dromaeo_css 16:00:32 INFO - 2015-11-20 16:00:32,811 DEBUG : operating with platform_type : linux_ 16:00:32 INFO - 2015-11-20 16:00:32,811 INFO : Initialising browser for dromaeo_css test... 16:00:32 INFO - 2015-11-20 16:00:32,818 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjLOMMC/profile http://localhost:55635/getInfo.html 16:00:37 INFO - 2015-11-20 16:00:37,820 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:00:37 INFO - 2015-11-20 16:00:37,820 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:00:37 INFO - 2015-11-20 16:00:37,820 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:00:37 INFO - 2015-11-20 16:00:37,820 DEBUG : BROWSER_OUTPUT: __metrics 16:00:37 INFO - 2015-11-20 16:00:37,997 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:00:39 INFO - 2015-11-20 16:00:39,238 INFO : Browser initialized. 16:00:39 INFO - 2015-11-20 16:00:39,238 INFO : Running cycle 1/1 for dromaeo_css test... 16:00:39 INFO - 2015-11-20 16:00:39,239 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjLOMMC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:00:50 INFO - 2015-11-20 16:00:50,977 DEBUG : BROWSER_OUTPUT: RSS: Main: 139534336 16:00:50 INFO - 2015-11-20 16:00:50,977 DEBUG : BROWSER_OUTPUT: 16:01:41 INFO - 2015-11-20 16:01:41,827 DEBUG : BROWSER_OUTPUT: 1448064101826 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] 16:03:51 INFO - 2015-11-20 16:03:51,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55635/tests/dromaeo/cssquery-ext.html) 16:03:52 INFO - 2015-11-20 16:03:52,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 228798464 16:03:52 INFO - 2015-11-20 16:03:52,784 DEBUG : BROWSER_OUTPUT: 16:06:55 INFO - 2015-11-20 16:06:55,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-ext.html (next: http://localhost:55635/tests/dromaeo/cssquery-jquery.html) 16:06:55 INFO - 2015-11-20 16:06:55,691 DEBUG : BROWSER_OUTPUT: RSS: Main: 229453824 16:06:55 INFO - 2015-11-20 16:06:55,691 DEBUG : BROWSER_OUTPUT: 16:09:57 INFO - 2015-11-20 16:09:57,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55635/tests/dromaeo/cssquery-mootools.html) 16:09:58 INFO - 2015-11-20 16:09:58,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 217047040 16:09:58 INFO - 2015-11-20 16:09:58,134 DEBUG : BROWSER_OUTPUT: 16:13:00 INFO - 2015-11-20 16:13:00,019 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55635/tests/dromaeo/cssquery-prototype.html) 16:13:00 INFO - 2015-11-20 16:13:00,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 197615616 16:13:00 INFO - 2015-11-20 16:13:00,621 DEBUG : BROWSER_OUTPUT: 16:16:03 INFO - 2015-11-20 16:16:03,140 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55635/tests/dromaeo/cssquery-yui.html) 16:16:03 INFO - 2015-11-20 16:16:03,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 203030528 16:16:03 INFO - 2015-11-20 16:16:03,709 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-yui.html (next: http://localhost:55635/tests/dromaeo/cssquery-dojo.html) 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 279887872 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:19:06 INFO - 2015-11-20 16:19:06,449 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3154.8107113573183;3239.206394433364;3273.852676629553;3281.5077738339246;3291.7860339077965;5173.974130129353;5291.126964050927;5294.179402192657;5431.72841357932;5487.7530511127;5301;5345.599080068991;5398.6220964532495;5411.594130440213;5412.485813847688;4912.852614421564;4987.251912213179;4993.775280112403;5007.4491805901325;5014.272930425104;4872.342233798445;5049.065922804275;5072.9492705073035;5084.79660813567;5128.666636668623;4839.370881785363;4923.458419573844;4929.901401971941;4936.259561065833;4952.925706114411;4955.40535135786;4961.429435614915;4983.352164218664;4985.5513003829465;5031.144705400091;4730.337752714623;4825.034993001379;4929.186684197103;4936.703797772109;4980.12847751642;5096.490350964911;5161.638685292031;5161.8193363232485;5175.482451754832;5178.352705911761;5778.884422311572;5779.653220806765;5784.797532086357;5800.4489573490455;5828.2131912191735;5759.107338362561;5829.650220986754;5829.825105246807;5900.055991041413;5907.438793314628;5308.124159513675;5402.810901618424;5413.296271484761;5421.484958928935;5467.507924286793;5855.970720146372;5877.500412464903;5880.411958804171;5885.323187833368;5915.3788852170765;5527.55779537636;5659.377468478464;5662.37713851476;5715.342735585461;5727.198192253088;5803.216565763568;5884.205632239678;5884.558658100574;5894.056950887837;5940.287165540163;5813.651180929073;5866.970665146647;5873.295204575478;5879.559033072536;5882.147088672119;5532.17017447378;5658.886822263614;5714.200011998323;5870.3249126350165;5875.823725288248;4495.505494395614;4526.886827829265;4529.886752831139;4556.498785133633;4566.26939689648;4018.1160144768105;4018.3771515415156;4023.839046438168;4029.0128918415176;4067.491563554556;5366.356037275552;5445.482679145475;5457.781688732406;5577.776888924479;5637.408072152365;5740.741666625012;5742.167385729046;5743.195952566644;5743.885122297531;5780.277465316835;5434.809781657702;5441.428649991773;5443.646162999472;5443.7822487100075;5454.318210223722;4908.950910490921;5028.069807085701;5056.873578160502;5139.897202056013;5181.455947125498;5988.9401105989255;5990.760369585255;5993.190919226023;6000.789972350947;6013.368596297326;5782.132680098019;5802.65184088956;5822.650640961556;5833.82498525045;5855.180274761452;5765.827025189251;5926.4666180044005;5970.910436343372;5973.223480947623;6058.212432383762;5795.623284486495;5824.854378640568;5836.854078647898;5852.531797456279;5853.678047707417;5910;5933.851653708692;5937.9406205937385;5945.316288626863;5945.881082378241;5529.115341545334;5534.363548192009;5624.8875022498505;5636.802711905064;5659.349174844946;5740.225069615715;5742.138679197987;5754.6547207167705;5761.251037365283;5778.913316300343;5426.294581704511;5616.494515493625;5628.071368224197;5637.46444087811;5651.463111004448;5543.584231182596;5585.832425027255;5676.5742569308295;5682.318121825242;5737.569682273763;5522.696251706034;5530.336359636869;5532.28080349552;5551.278333816578;5639.8590035249445 16:19:06 INFO - 2015-11-20 16:19:06,450 DEBUG : BROWSER_OUTPUT: |1;ext.html;561.5389045671832;584.9912251316229;585.2713371852045;604.9183360246367;609.4332270987979;31545.369092618133;32293.51559726606;32393.83803080987;32423.83788081059;32429.67570324299;41070;41431.378529322086;41434.792826035904;41486.17027659445;41512.79243603778;8735.606897689588;8878.200961913426;8900.37697361185;8906.554672266393;8911.064338244489;7902.841943161134;8089.514629122242;8686.826263474726;8805.20753132218;10343.069123778858;10465.529051192714;10576.78846423071;10577.682669519889;10578.047975682188;10579.682609521727;10456.999999999962;10551.10315623173;10574.312669676447;10577.471126443725;10580.788384232272;7883.2905038546805;8089.47418417801;8098.838023239561;10450.111740502067;10759.56961721538;38829.61170388288;39300.60699392998;39304.60695393038;39315.80342098271;39366.40950385747;3034.5903303054247;3085.2903832118736;3087.81473111614;3161.44674681932;3411.1642647551507;29390.559141612892;29657.555136672967;29902;30121.548176777367;30780;3746.831392587313;3852.2295540891655;3880.1269714314335;3903.902402439962;3963.093015308564;280984;281581;284097.9999999979;284151;284477;55725.44274557284;56667;56988.14517782236;57003.14495282574;57089.71455142698;66599;67407.66296168488;67860;68027;68088.6595567019;14698.632534186518;14869.256537173098;14901.329440175217;14907.105573665614;14907.70184596302;12211.450484728208;12386;12390.132690711564;12391;12397.566085186974;19044.33344832952;19280.517987050436;19368.612627747367;19374.70937935932;19405.611887762447;7508.361789247975;7715.807104822312;7726.922730772732;7745.806354841061;7766.689332426639;931.8276118918022;976.272676855747;994.5623925472769;1036.6889933020063;1048.695878195315;27626.447471050466;27912.581311280348;28178.71821281802;28310.43379132406;28421.43157136887;1050.81610718124;1071.3893080943942;1084.6529110684505;1146.633077415236;1156.4795841871191;14600.780988284972;14894.627634309229;14964;15111.168885711391;15200.847991519944;307737;312468;313045;313288;313357;51592.4203789809;52296.738516307174;52311.21533177005;52369.21446178311;52385.47614523806;1323.9139455935333;1328.1499840102342;1328.6678330417203;1330.2949436798513;1350.547566565192;25154.496910062062;26905.865470673303;27788.583171252852;29013.56479652849;29413.26466838346;43202.13595728131;43288.13423731571;43618;43732.99999999873;43825;271077;274646;275014;275247;283408;26247.21258362252;31667.84166079155;31823.68176318207;31918;32163.195920101247;39679.801600991814;42675;42838.35742463928;43702.56297437112;43904.34143487914;35902;36057.27885442224;36347.27305453823;36399.45203835812;36748.26503469969;35638.465423018155;35703.82148089243;36522.65679056568;36956.63043369532;36998;35377.64622353847;35794.28411431809;36198.63801361953;36203.81898090493;36237.45643815292;30945.381092378477;31349;33237.833810830794;33325.66674333226;33332.500012499346;24462.877685611456;24513.387165321008;24612.261632151065;24726.258212253662;25038.874805625856 16:19:06 INFO - 2015-11-20 16:19:06,450 DEBUG : BROWSER_OUTPUT: |2;jquery.html;360.4269211952995;360.50430657845465;361.8425984696657;363.4348587945745;365.26763838503797;13631.591052268423;13816.101953373023;16441.671166576678;16760;17167.48497545074;15712.528624141294;15768;16589.41937032204;16727.247273872676;16729.414470493535;4653.976125252448;4817.156997525437;4823.397075365579;4823.5176482351835;4830.565249127578;3677.411614141738;4750.524947505239;4768.308595253703;5675.858103547403;6006.609570377933;5941.316748573904;5943.01940179872;5965.522758179357;5998.580099393044;6006.699665016754;5097.23541468779;5189.766460509286;5205;5228.189630607243;5230.92153617696;4934.481879402683;5006.924896126561;5013.548780609726;5189.403218629868;5194.92207616886;19358.612827743367;19744.40766776999;20033.8998305009;20771.68842467364;20805.271815486387;1961.18610776529;2056.7840376760373;2056.9177232910665;2099.548597051641;2129.2015494189677;9514.952425237829;10867;12266.509339626404;12787.104902656818;13291.86708132916;1736.053850651398;1812.5831058856404;1832.1480511562188;1860.7115897035847;1866.1975350599237;60960.390396095914;64367.3563264366;64781;64930;65742.3425765741;9628.133467987915;9629.903700962901;9693.466859322663;9814;10038.498075096217;12129.939350303368;12209.084318676134;12209.755804883853;12231.266124032587;12251.632451026484;7580.431467639949;7622.9237707622215;7780.416468764865;7793.06483222017;7834;7742;7763.534187948741;7791.766247012599;7830.138684744674;7832.0601527817025;4019.9196016079513;4052.371882358239;4069.5116586009276;4200.894977625584;4296.076343586144;1630.2663801289468;1693.7459381092935;1769.3364988129451;1773.272958087178;1790.579213884736;913.3652111782247;938.5682586010375;938.9577469013774;1047.2250534604339;1056.265895202842;16596.58508537296;16921.407750728664;17094.743578846326;17288.394906178222;17369.56576085608;947.924166066713;953.5899563187758;967.8112768010311;1028.753099256173;1030.340582027503;9508.809823803624;9820.263480239011;9845.458499782437;9864.506774661239;9943.850842237372;349865;354030;375183;506053;522100;41893;66859.66570167118;69166;77182.6140869292;77844.68843234243;1082.8971247731454;1093.7429704019548;1104.9944750276197;1109.328856042099;1112.8998390144925;14993.700125997637;15301.540953771404;15495.45765898188;15788.526344209693;15924.522264332089;32767;32983.73415826745;32988.67011329856;35727.46408803922;36654;43137.78431107824;45785;46690.766546167055;47169.76415117903;49101;9142;9195.632174712937;9313.953430233076;9348.392354496937;9570.760730981781;17281.827181728524;17340.30638774435;17376.218070187126;17656.73514897252;17730.202140903948;14628.122312661275;14741.262936853329;14761.778573321624;14845.703085938438;15242.161681107646;14175.787363189354;14244.359003844645;14350.784738228725;14612;14992.775108373165;14605.634859128606;14925;14985.850141498446;15096.54710358691;15328.770068448759;9341.766455838386;9352.766180845534;9355.532223388667;9473.431594104375;9545.043266130566;11432.371219583003;14690.706185876435;14741.55775326742;14973.401063957319;15091.471798487 16:19:06 INFO - 2015-11-20 16:19:06,450 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1328.4686125549786;1414.3988804757978;1483.3176738700197;1599.3122957128437;1767.0634563681244;6817.6932038058285;7531.058617672791;7838.294553490192;7843.333316668076;7846.921530784704;7654.808629784258;7718.6912523498995;7784.727534536292;7790.571518566477;7795.18150594188;1990.2138655231247;2056.0644906567477;2060.5878824235137;2263.54729054189;2289.198780426854;2296.5866144094057;2331.40549159964;2333.0434521846046;2334.5681049005902;2336.5910965581043;2238.55228954209;2245.92195746042;2306.792388685018;2306.8615883047073;2310.1221535816367;1953.9706904396373;2011.1251605551631;2014.9496262593407;2015.6371853066441;2242.932712018642;2241.2043724477903;2302.70064891563;2304.2626359564947;2306.815454763615;2308.907643694267;7622.8475430491635;7768.805779855492;7812.53124812513;7813.335866451358;7820.648070836826;4669.626429885601;4736.976315118437;4739.691920025188;4758.214894542396;4771.427428708543;3045.162580290416;3061.785675002751;3089.9845500772576;3107.5027995520836;3108.036508682316;995.7311525888042;1003.5433877585681;1005.1606908231636;1008.5209525475341;1042.9113525350353;10833.566657333697;10847.349159050482;10862.34825910448;10875.564977400894;10924.563017479291;4317.762523061199;4335.3713711511655;4376.212281789245;4378.190034843564;4437.955620443819;9255.537223138816;9261.44431334122;9264.351495395393;9270.860937085949;9281.95359023214;4740.905181896412;4759.143354196278;4775.5224477551965;4789.185838407479;4792.161371759928;4342.5657434256955;4384.386185933972;4390.297552391666;4394.055278115221;4397.252467080604;3698.6856117230336;3718.8326525306425;3740.401535754266;3740.5698344690163;3757.079515518715;1247.5134697467995;1261.369315342329;1265.6139877337316;1268.5623459906317;1270.4664041102758;1224.4612370556927;1232.6671798614325;1253.179167645194;1265.1966001589058;1265.2851139106376;7102.786916392516;7194.136703595497;7198;7207.279272072751;7216.386607138452;475.5007242395471;476.8283417969528;481.79764498910544;482.16103979076155;498.22277247494026;5471.6169868108855;5562.3047119110115;5572.86067848307;5638.718064096779;5680.204771332017;9262.073792620684;9376.203022743006;9519.524023798782;9574;9592.760180995392;9038.367314287936;9187.63249470027;9305.348625596143;9335.253179745605;9365.484898330657;6934.4452443804375;6992.265812089658;7003.124609423822;7054.6472676365975;7126.00235966965;6741.662916854236;6991.685374158277;7031.191412987572;7044.049053377728;7089.680964356513;8074.152214017444;8479.279261262862;8480.10958849312;8510.446820956618;8623.310135189295;8534.70128545498;8690.91309086901;8703.782405439915;8893.64425422976;8895.06601806801;6878.931210687829;6898.655067246718;6916.23921368639;6928;6930.757423490154;7326.120865496175;7511.737089201852;7533.058367704037;7567.129780075362;7567.697292108474;6833.726650933907;6951.895721564079;7084.752033679003;7089.858202835811;7135.964320178366;6756.932430675827;6770.187577490733;6824.7952561423235;6849.48628852848;6859.897101543581;7010;7041.718331266692;7045.718171273296;7051.682674279567;7052.612106334201;6135.938640613536;6247.656378899204;6257.374262573889;6263.937360626335;6268.059791031382;6147.231596050494;6177.62934223948;6184.350643182401;6187.102870083722;6193.783217587363 16:19:06 INFO - 2015-11-20 16:19:06,450 DEBUG : BROWSER_OUTPUT: |4;prototype.html;311.0962653491608;314.4418656884029;333.3682671337815;338.8373580681273;340.6219096802549;5848.356680765112;6020.698965051746;6037.788677396292;6110.388961103888;6122.387761223876;5806.680632565218;5953.821385358447;5976.3724808895095;5976.820695379135;5989.490893274066;4635.212013957627;4639.907201855961;4642.605378542828;4646.954435252075;4649.232876575361;4457.576530229623;4564.087182563484;4571.862844114666;4629.606483448894;4646.512116227798;4579.862604121865;4582.839600613979;4594.724316541002;4600.608948239387;4617.468991066037;4572.062727140945;4584.564466375689;4596.724196548184;4600.9769951150365;4607.239805432099;4044.5955404459614;4051.169510250392;4053.716239863211;4060.309747342959;4075.8981025474304;5583.05088135018;5684.602077854552;5685.374608793029;5685.8009969651275;5688.11834165705;5363.731813409314;5368.91946620801;5369.167778994262;5374.7850085996515;5380.085385484477;6089.330173680892;6308.148399965991;6334.113224148623;6342.175517182736;6347.206599175103;4376.781160941972;4393.758343291118;4481.484629267643;4483.461984561874;4506.121306345264;6594.53838231324;6634.966825165891;6644.700988455531;6652.0354548590185;6656.36764507371;4617.822455273884;4622.191116554657;4625.121226966866;4625.444946606429;4626.629869610423;4388.8902777430185;4456.46522417312;4508.504064552896;4679.64902632304;4683.531646835358;5205.5835533158015;5211.009908117446;5248.396434410015;5265.631405801557;5266.710330931835;6425.614463132227;6465.870682586322;6472.708728107153;6474.12599299098;6477.1579694639395;1605.7109720250467;1615.4023011485826;1616.2322896624244;1616.9919150404173;1618.902865828054;1055.4458909072798;1066.1151244467073;1067.1036329483272;1095.05277934586;1097.4567589043315;6231.719572619243;6237.656928868865;6238.781642642486;6261.812145635638;6266.937330626635;6671.532992690562;6671.666416679147;6678.23200331968;6685.699143538553;6686.699098540577;6061.121137435047;6207.751689932352;6221.3156552779155;6228.190335256388;6242.687865606701;3570.071781336819;3595.7123430125525;3597.5323207982797;3598.586162591283;3602.189507360823;6844.760433384808;6844.794656160323;6844.965775171092;6845.349691779273;6847.486438517031;6647.734090636321;6762.763303284361;6772.984052392082;6798.694058767367;6804.455643548505;6826.795196144124;6827.0783444235385;6829.146356705412;6829.795106146724;6835.58986460814;6428.8071357859335;6568.671566421564;6596.934030659632;6607.075009498578;6619.569727967667;6583.177102862142;6583.835404114935;6632.204135503771;6638.5684930479365;6640.103586015826;6870.553414028072;6889.896651550331;6891.827704307432;6892.655367231719;6901.723931042702;4387.166438376698;4488.775561221991;4513.842015529572;4523.0953809238945;4530.161920044802;6621.106150669598;6622.304658010839;6627.5360724750735;6634;6636.037774522763;6437.742490300336;6443.387878151568;6444.742210311535;6447.2263328400895;6448.355164483514;6315.115883776183;6433.067205255305;6433.453156481752;6476.870462590816;6511.120998665119;6375.458086062737;6376.171097757262;6377.043443483514;6383.680815959091;6393.6483493408305;6542.640154791532;6546.279909209888;6548.148740663683;6553.5084868634085;6555.180602424697;6186.1030150627;6316.273628532594;6333.429991300805;6337.651429171256;6350.047492876106 16:19:06 INFO - 2015-11-20 16:19:06,451 DEBUG : BROWSER_OUTPUT: |5;yui.html;833.8374017066675;837.2297486312592;840.3949156607242;849.2229609906935;849.9575021248938;12258.877411225885;12499.500019999188;12503.562375316866;12507.49970001199;12512.49950002;10794.406307653077;11794;11842.881571184285;11873.406329683503;11873.584424545143;665.3446355339986;795.129333379948;818.8239528501371;819.7499762572413;819.8770184472318;802.5987006496752;805.8267472493412;807.7011505742884;809.7894547417684;813.2396209544065;685.1367277230676;693.8404167041583;833.3583140981441;839.7438781171741;840.7393707950555;844.2275318083946;859.8753180788813;859.9398042137053;860.182826314997;861.8147098373848;625.4027403829332;628.1614045249597;630.0423356498097;630.3822254190873;633.2527617411453;11869.52521899123;11921.88078119225;12021.939890300493;12051.638450846402;12065.155439119266;11930.22453540517;11975.64073077809;12006.399680015964;12243.081768867281;12354.938225308906;9853.90146098537;9989.950050249703;10049.145822605087;10068.446235457048;10161.136303414218;3587.157018100744;3602.7297952653657;3608.9097772555633;3624.5469316335457;3718.1262403335463;47736;47852.7607361961;47986;49619.25571116436;49869.501304987214;9901;9982.450965196838;9985.30102892805;10034.899651003396;10094.49527523621;19044.71432928507;19072.61854762897;19074.809251907303;19075.046247687562;19077.904610477137;6931.029655848185;6970.965145174242;7213.531120477152;7271.745488907863;7391.2978267064545;4244.745315281093;4324.870253892325;4355.324924636687;4370.2352088384405;4378.824847006084;2960.6003189569133;2960.6743258241577;2973.9702602973694;2986.1190948670196;2991.3718119195214;538.1148011521026;544.6242092955848;546.1643685161708;546.9316335458068;550.5182964905707;610.6427739772195;615.9815205543841;661.256086902235;667.519386042049;690.7513295213719;8493.745187644381;8631.309495240366;8647.264982476434;8804.251638610662;8808.735737927873;635.4820821030845;657.4062156569614;676.0569006236273;678.5521555773166;679.6771533521538;3992.8203230854683;4097.672186225095;4121.340585506365;4163.791810409467;4167.432982567746;55925.72037139788;56256;56461.7176914121;56476.71761641165;57167.42832571704;49863.75068124636;50949.490505095215;51251.743741281054;51367.22949155766;51508;46027.76986115115;46315;46684.832879177615;46709.29936050962;47137.292940605235;7797.532148071134;7968.282854543119;8187.426880118573;8935.821283574422;9174.036726143659;35631.10922226965;35839.46240806338;35930.8203458981;37047.25905481929;37659.43510847285;40483.39274910938;42128.57871421369;42586.78706606447;44393.55606443894;45113.32330014987;11087.22389432732;11182.944085279521;11273;11309.830352544883;11504.769904602028;12597.937010315256;12818.102732808615;12819.935900320812;12959.740805184032;13028.869711302767;11219.943900280447;11529.769404611692;11640.417979101181;11643.53425862956;11997.82003269969;11677.870302027539;12122.636320910387;12174.939125304316;12257.754844903231;12636.810447843474;11781.116416268642;11823.940880295544;11837.526498939946;12000.279983201035;12207;9880.752981175528;10006.649767258403;10231.334963227366;10396.584136634752;10452.581896724047;8786.341024423322;9032.954835225783;9506.762330941781;9538.475383853955;9774.12032917041 16:19:06 INFO - 2015-11-20 16:19:06,451 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:19:06 INFO - 2015-11-20 16:19:06,451 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:19:06 INFO - 2015-11-20 16:19:06,451 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1012 16:19:06 INFO - 2015-11-20 16:19:06,451 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:19:06 INFO - 2015-11-20 16:19:06,451 DEBUG : BROWSER_OUTPUT: __startTimestamp1448065146443__endTimestamp 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5392.83 Median:5551.28 stddev:590.81 (10.6%) stddev-sans-first:565.93 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: Values: 3154.8 3239.2 3273.9 3281.5 3291.8 5174.0 5291.1 5294.2 5431.7 5487.8 5301.0 5345.6 5398.6 5411.6 5412.5 4912.9 4987.3 4993.8 5007.4 5014.3 4872.3 5049.1 5072.9 5084.8 5128.7 4839.4 4923.5 4929.9 4936.3 4952.9 4955.4 4961.4 4983.4 4985.6 5031.1 4730.3 4825.0 4929.2 4936.7 4980.1 5096.5 5161.6 5161.8 5175.5 5178.4 5778.9 5779.7 5784.8 5800.4 5828.2 5759.1 5829.7 5829.8 5900.1 5907.4 5308.1 5402.8 5413.3 5421.5 5467.5 5856.0 5877.5 5880.4 5885.3 5915.4 5527.6 5659.4 5662.4 5715.3 5727.2 5803.2 5884.2 5884.6 5894.1 5940.3 5813.7 5867.0 5873.3 5879.6 5882.1 5532.2 5658.9 5714.2 5870.3 5875.8 4495.5 4526.9 4529.9 4556.5 4566.3 4018.1 4018.4 4023.8 4029.0 4067.5 5366.4 5445.5 5457.8 5577.8 5637.4 5740.7 5742.2 5743.2 5743.9 5780.3 5434.8 5441.4 5443.6 5443.8 5454.3 4909.0 5028.1 5056.9 5139.9 5181.5 5988.9 5990.8 5993.2 6000.8 6013.4 5782.1 5802.7 5822.7 5833.8 5855.2 5765.8 5926.5 5970.9 5973.2 6058.2 5795.6 5824.9 5836.9 5852.5 5853.7 5910.0 5933.9 5937.9 5945.3 5945.9 5529.1 5534.4 5624.9 5636.8 5659.3 5740.2 5742.1 5754.7 5761.3 5778.9 5426.3 5616.5 5628.1 5637.5 5651.5 5543.6 5585.8 5676.6 5682.3 5737.6 5522.7 5530.3 5532.3 5551.3 5639.9 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46014.34 Median:32040.60 stddev:75928.12 (237.0%) stddev-sans-first:76064.42 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: Values: 561.5 585.0 585.3 604.9 609.4 31545.4 32293.5 32393.8 32423.8 32429.7 41070.0 41431.4 41434.8 41486.2 41512.8 8735.6 8878.2 8900.4 8906.6 8911.1 7902.8 8089.5 8686.8 8805.2 10343.1 10465.5 10576.8 10577.7 10578.0 10579.7 10457.0 10551.1 10574.3 10577.5 10580.8 7883.3 8089.5 8098.8 10450.1 10759.6 38829.6 39300.6 39304.6 39315.8 39366.4 3034.6 3085.3 3087.8 3161.4 3411.2 29390.6 29657.6 29902.0 30121.5 30780.0 3746.8 3852.2 3880.1 3903.9 3963.1 280984.0 281581.0 284098.0 284151.0 284477.0 55725.4 56667.0 56988.1 57003.1 57089.7 66599.0 67407.7 67860.0 68027.0 68088.7 14698.6 14869.3 14901.3 14907.1 14907.7 12211.5 12386.0 12390.1 12391.0 12397.6 19044.3 19280.5 19368.6 19374.7 19405.6 7508.4 7715.8 7726.9 7745.8 7766.7 931.8 976.3 994.6 1036.7 1048.7 27626.4 27912.6 28178.7 28310.4 28421.4 1050.8 1071.4 1084.7 1146.6 1156.5 14600.8 14894.6 14964.0 15111.2 15200.8 307737.0 312468.0 313045.0 313288.0 313357.0 51592.4 52296.7 52311.2 52369.2 52385.5 1323.9 1328.1 1328.7 1330.3 1350.5 25154.5 26905.9 27788.6 29013.6 29413.3 43202.1 43288.1 43618.0 43733.0 43825.0 271077.0 274646.0 275014.0 275247.0 283408.0 26247.2 31667.8 31823.7 31918.0 32163.2 39679.8 42675.0 42838.4 43702.6 43904.3 35902.0 36057.3 36347.3 36399.5 36748.3 35638.5 35703.8 36522.7 36956.6 36998.0 35377.6 35794.3 36198.6 36203.8 36237.5 30945.4 31349.0 33237.8 33325.7 33332.5 24462.9 24513.4 24612.3 24726.3 25038.9 16:19:06 INFO - 2015-11-20 16:19:06,452 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,453 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25284.56 Median:360.47 stddev:70121.37 (19453.0%) stddev-sans-first:70293.11 16:19:06 INFO - 2015-11-20 16:19:06,454 DEBUG : BROWSER_OUTPUT: Values: 360.4 360.5 361.8 363.4 365.3 13631.6 13816.1 16441.7 16760.0 17167.5 15712.5 15768.0 16589.4 16727.2 16729.4 4654.0 4817.2 4823.4 4823.5 4830.6 3677.4 4750.5 4768.3 5675.9 6006.6 5941.3 5943.0 5965.5 5998.6 6006.7 5097.2 5189.8 5205.0 5228.2 5230.9 4934.5 5006.9 5013.5 5189.4 5194.9 19358.6 19744.4 20033.9 20771.7 20805.3 1961.2 2056.8 2056.9 2099.5 2129.2 9515.0 10867.0 12266.5 12787.1 13291.9 1736.1 1812.6 1832.1 1860.7 1866.2 60960.4 64367.4 64781.0 64930.0 65742.3 9628.1 9629.9 9693.5 9814.0 10038.5 12129.9 12209.1 12209.8 12231.3 12251.6 7580.4 7622.9 7780.4 7793.1 7834.0 7742.0 7763.5 7791.8 7830.1 7832.1 4019.9 4052.4 4069.5 4200.9 4296.1 1630.3 1693.7 1769.3 1773.3 1790.6 913.4 938.6 939.0 1047.2 1056.3 16596.6 16921.4 17094.7 17288.4 17369.6 947.9 953.6 967.8 1028.8 1030.3 9508.8 9820.3 9845.5 9864.5 9943.9 349865.0 354030.0 375183.0 506053.0 522100.0 41893.0 66859.7 69166.0 77182.6 77844.7 1082.9 1093.7 1105.0 1109.3 1112.9 14993.7 15301.5 15495.5 15788.5 15924.5 32767.0 32983.7 32988.7 35727.5 36654.0 43137.8 45785.0 46690.8 47169.8 49101.0 9142.0 9195.6 9314.0 9348.4 9570.8 17281.8 17340.3 17376.2 17656.7 17730.2 14628.1 14741.3 14761.8 14845.7 15242.2 14175.8 14244.4 14350.8 14612.0 14992.8 14605.6 14925.0 14985.9 15096.5 15328.8 9341.8 9352.8 9355.5 9473.4 9545.0 11432.4 14690.7 14741.6 14973.4 15091.5 16:19:06 INFO - 2015-11-20 16:19:06,454 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,454 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5423.82 Median:6162.43 stddev:2851.53 (46.3%) stddev-sans-first:2842.91 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: Values: 1328.5 1414.4 1483.3 1599.3 1767.1 6817.7 7531.1 7838.3 7843.3 7846.9 7654.8 7718.7 7784.7 7790.6 7795.2 1990.2 2056.1 2060.6 2263.5 2289.2 2296.6 2331.4 2333.0 2334.6 2336.6 2238.6 2245.9 2306.8 2306.9 2310.1 1954.0 2011.1 2014.9 2015.6 2242.9 2241.2 2302.7 2304.3 2306.8 2308.9 7622.8 7768.8 7812.5 7813.3 7820.6 4669.6 4737.0 4739.7 4758.2 4771.4 3045.2 3061.8 3090.0 3107.5 3108.0 995.7 1003.5 1005.2 1008.5 1042.9 10833.6 10847.3 10862.3 10875.6 10924.6 4317.8 4335.4 4376.2 4378.2 4438.0 9255.5 9261.4 9264.4 9270.9 9282.0 4740.9 4759.1 4775.5 4789.2 4792.2 4342.6 4384.4 4390.3 4394.1 4397.3 3698.7 3718.8 3740.4 3740.6 3757.1 1247.5 1261.4 1265.6 1268.6 1270.5 1224.5 1232.7 1253.2 1265.2 1265.3 7102.8 7194.1 7198.0 7207.3 7216.4 475.5 476.8 481.8 482.2 498.2 5471.6 5562.3 5572.9 5638.7 5680.2 9262.1 9376.2 9519.5 9574.0 9592.8 9038.4 9187.6 9305.3 9335.3 9365.5 6934.4 6992.3 7003.1 7054.6 7126.0 6741.7 6991.7 7031.2 7044.0 7089.7 8074.2 8479.3 8480.1 8510.4 8623.3 8534.7 8690.9 8703.8 8893.6 8895.1 6878.9 6898.7 6916.2 6928.0 6930.8 7326.1 7511.7 7533.1 7567.1 7567.7 6833.7 6951.9 7084.8 7089.9 7136.0 6756.9 6770.2 6824.8 6849.5 6859.9 7010.0 7041.7 7045.7 7051.7 7052.6 6135.9 6247.7 6257.4 6263.9 6268.1 6147.2 6177.6 6184.4 6187.1 6193.8 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5365.73 Median:6116.39 stddev:1624.57 (26.6%) stddev-sans-first:1584.21 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: Values: 311.1 314.4 333.4 338.8 340.6 5848.4 6020.7 6037.8 6110.4 6122.4 5806.7 5953.8 5976.4 5976.8 5989.5 4635.2 4639.9 4642.6 4647.0 4649.2 4457.6 4564.1 4571.9 4629.6 4646.5 4579.9 4582.8 4594.7 4600.6 4617.5 4572.1 4584.6 4596.7 4601.0 4607.2 4044.6 4051.2 4053.7 4060.3 4075.9 5583.1 5684.6 5685.4 5685.8 5688.1 5363.7 5368.9 5369.2 5374.8 5380.1 6089.3 6308.1 6334.1 6342.2 6347.2 4376.8 4393.8 4481.5 4483.5 4506.1 6594.5 6635.0 6644.7 6652.0 6656.4 4617.8 4622.2 4625.1 4625.4 4626.6 4388.9 4456.5 4508.5 4679.6 4683.5 5205.6 5211.0 5248.4 5265.6 5266.7 6425.6 6465.9 6472.7 6474.1 6477.2 1605.7 1615.4 1616.2 1617.0 1618.9 1055.4 1066.1 1067.1 1095.1 1097.5 6231.7 6237.7 6238.8 6261.8 6266.9 6671.5 6671.7 6678.2 6685.7 6686.7 6061.1 6207.8 6221.3 6228.2 6242.7 3570.1 3595.7 3597.5 3598.6 3602.2 6844.8 6844.8 6845.0 6845.3 6847.5 6647.7 6762.8 6773.0 6798.7 6804.5 6826.8 6827.1 6829.1 6829.8 6835.6 6428.8 6568.7 6596.9 6607.1 6619.6 6583.2 6583.8 6632.2 6638.6 6640.1 6870.6 6889.9 6891.8 6892.7 6901.7 4387.2 4488.8 4513.8 4523.1 4530.2 6621.1 6622.3 6627.5 6634.0 6636.0 6437.7 6443.4 6444.7 6447.2 6448.4 6315.1 6433.1 6433.5 6476.9 6511.1 6375.5 6376.2 6377.0 6383.7 6393.6 6542.6 6546.3 6548.1 6553.5 6555.2 6186.1 6316.3 6333.4 6337.7 6350.0 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13735.22 Median:46499.92 stddev:15870.62 (34.1%) stddev-sans-first:15885.57 16:19:06 INFO - 2015-11-20 16:19:06,455 DEBUG : BROWSER_OUTPUT: Values: 833.8 837.2 840.4 849.2 850.0 12258.9 12499.5 12503.6 12507.5 12512.5 10794.4 11794.0 11842.9 11873.4 11873.6 665.3 795.1 818.8 819.7 819.9 802.6 805.8 807.7 809.8 813.2 685.1 693.8 833.4 839.7 840.7 844.2 859.9 859.9 860.2 861.8 625.4 628.2 630.0 630.4 633.3 11869.5 11921.9 12021.9 12051.6 12065.2 11930.2 11975.6 12006.4 12243.1 12354.9 9853.9 9990.0 10049.1 10068.4 10161.1 3587.2 3602.7 3608.9 3624.5 3718.1 47736.0 47852.8 47986.0 49619.3 49869.5 9901.0 9982.5 9985.3 10034.9 10094.5 19044.7 19072.6 19074.8 19075.0 19077.9 6931.0 6971.0 7213.5 7271.7 7391.3 4244.7 4324.9 4355.3 4370.2 4378.8 2960.6 2960.7 2974.0 2986.1 2991.4 538.1 544.6 546.2 546.9 550.5 610.6 616.0 661.3 667.5 690.8 8493.7 8631.3 8647.3 8804.3 8808.7 635.5 657.4 676.1 678.6 679.7 3992.8 4097.7 4121.3 4163.8 4167.4 55925.7 56256.0 56461.7 56476.7 57167.4 49863.8 50949.5 51251.7 51367.2 51508.0 46027.8 46315.0 46684.8 46709.3 47137.3 7797.5 7968.3 8187.4 8935.8 9174.0 35631.1 35839.5 35930.8 37047.3 37659.4 40483.4 42128.6 42586.8 44393.6 45113.3 11087.2 11182.9 11273.0 11309.8 11504.8 12597.9 12818.1 12819.9 12959.7 13028.9 11219.9 11529.8 11640.4 11643.5 11997.8 11677.9 12122.6 12174.9 12257.8 12636.8 11781.1 11823.9 11837.5 12000.3 12207.0 9880.8 10006.6 10231.3 10396.6 10452.6 8786.3 9033.0 9506.8 9538.5 9774.1 16:19:06 INFO - 2015-11-20 16:19:06,456 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:19:06 INFO - 2015-11-20 16:19:06,456 DEBUG : BROWSER_OUTPUT: 16:19:07 INFO - 2015-11-20 16:19:07,062 INFO : Browser exited with error code: 0 16:19:07 INFO - 2015-11-20 16:19:07,084 INFO : Completed test dromaeo_css (00:18:34) 16:19:07 INFO - 2015-11-20 16:19:07,084 INFO : Starting test kraken 16:19:07 INFO - 2015-11-20 16:19:07,084 DEBUG : operating with platform_type : linux_ 16:19:07 INFO - 2015-11-20 16:19:07,084 INFO : Initialising browser for kraken test... 16:19:07 INFO - 2015-11-20 16:19:07,090 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpprGX87/profile http://localhost:55635/getInfo.html 16:19:10 INFO - 2015-11-20 16:19:10,965 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:19:10 INFO - 2015-11-20 16:19:10,966 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:19:10 INFO - 2015-11-20 16:19:10,966 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:19:10 INFO - 2015-11-20 16:19:10,966 DEBUG : BROWSER_OUTPUT: __metrics 16:19:11 INFO - 2015-11-20 16:19:11,054 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:12 INFO - 2015-11-20 16:19:12,460 INFO : Browser initialized. 16:19:12 INFO - 2015-11-20 16:19:12,460 INFO : Running cycle 1/1 for kraken test... 16:19:12 INFO - 2015-11-20 16:19:12,460 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpprGX87/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:19:24 INFO - 2015-11-20 16:19:24,216 DEBUG : BROWSER_OUTPUT: RSS: Main: 142090240 16:19:24 INFO - 2015-11-20 16:19:24,217 DEBUG : BROWSER_OUTPUT: 16:19:26 INFO - 2015-11-20 16:19:26,691 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:27 INFO - 2015-11-20 16:19:27,272 DEBUG : BROWSER_OUTPUT: RSS: Main: 179027968 16:19:27 INFO - 2015-11-20 16:19:27,272 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2015-11-20 16:19:29,877 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-dft) 16:19:30 INFO - 2015-11-20 16:19:30,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 167972864 16:19:30 INFO - 2015-11-20 16:19:30,446 DEBUG : BROWSER_OUTPUT: 16:19:33 INFO - 2015-11-20 16:19:33,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-fft) 16:19:33 INFO - 2015-11-20 16:19:33,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 151662592 16:19:33 INFO - 2015-11-20 16:19:33,858 DEBUG : BROWSER_OUTPUT: 16:19:35 INFO - 2015-11-20 16:19:35,829 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-oscillator) 16:19:36 INFO - 2015-11-20 16:19:36,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 153042944 16:19:36 INFO - 2015-11-20 16:19:36,395 DEBUG : BROWSER_OUTPUT: 16:19:38 INFO - 2015-11-20 16:19:38,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55635/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:19:39 INFO - 2015-11-20 16:19:39,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 155250688 16:19:39 INFO - 2015-11-20 16:19:39,113 DEBUG : BROWSER_OUTPUT: 16:19:42 INFO - 2015-11-20 16:19:42,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55635/tests/kraken/driver.html?testName=imaging-darkroom) 16:19:42 INFO - 2015-11-20 16:19:42,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 156143616 16:19:42 INFO - 2015-11-20 16:19:42,572 DEBUG : BROWSER_OUTPUT: 16:19:45 INFO - 2015-11-20 16:19:45,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55635/tests/kraken/driver.html?testName=imaging-desaturate) 16:19:46 INFO - 2015-11-20 16:19:46,518 DEBUG : BROWSER_OUTPUT: RSS: Main: 155308032 16:19:46 INFO - 2015-11-20 16:19:46,519 DEBUG : BROWSER_OUTPUT: 16:19:49 INFO - 2015-11-20 16:19:49,278 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55635/tests/kraken/driver.html?testName=json-parse-financial) 16:19:49 INFO - 2015-11-20 16:19:49,864 DEBUG : BROWSER_OUTPUT: RSS: Main: 155521024 16:19:49 INFO - 2015-11-20 16:19:49,864 DEBUG : BROWSER_OUTPUT: 16:19:51 INFO - 2015-11-20 16:19:51,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55635/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:19:51 INFO - 2015-11-20 16:19:51,993 DEBUG : BROWSER_OUTPUT: RSS: Main: 168976384 16:19:51 INFO - 2015-11-20 16:19:51,994 DEBUG : BROWSER_OUTPUT: 16:19:53 INFO - 2015-11-20 16:19:53,804 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:19:54 INFO - 2015-11-20 16:19:54,398 DEBUG : BROWSER_OUTPUT: RSS: Main: 169136128 16:19:54 INFO - 2015-11-20 16:19:54,398 DEBUG : BROWSER_OUTPUT: 16:19:56 INFO - 2015-11-20 16:19:56,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:19:57 INFO - 2015-11-20 16:19:57,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 173797376 16:19:57 INFO - 2015-11-20 16:19:57,004 DEBUG : BROWSER_OUTPUT: 16:19:59 INFO - 2015-11-20 16:19:59,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:19:59 INFO - 2015-11-20 16:19:59,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 183025664 16:19:59 INFO - 2015-11-20 16:19:59,974 DEBUG : BROWSER_OUTPUT: 16:20:02 INFO - 2015-11-20 16:20:02,522 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:03 INFO - 2015-11-20 16:20:03,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 181014528 16:20:03 INFO - 2015-11-20 16:20:03,096 DEBUG : BROWSER_OUTPUT: 16:20:04 INFO - 2015-11-20 16:20:04,745 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55635/tests/kraken/driver.html?testName=ai-astar) 16:20:04 INFO - 2015-11-20 16:20:04,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 240205824 16:20:04 INFO - 2015-11-20 16:20:04,995 DEBUG : BROWSER_OUTPUT: 16:20:04 INFO - 2015-11-20 16:20:04,995 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:20:04 INFO - 2015-11-20 16:20:04,995 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:20:04 INFO - 2015-11-20 16:20:04,995 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;113;103;110;110;110;99;111;114;109 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;133;152;161;140;150;149;126;164;124 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;162;172;161;159;160;163;159;161 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;85;95;70;71;71;132;88;72 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;134;90;90;90;139;90;90;133 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;109;109;110;109;109;110 16:20:04 INFO - 2015-11-20 16:20:04,996 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;145;146;145;146;145;146;145 16:20:04 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;96;95;96;95;96;95;96 16:20:04 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;58;58;57;60;59;58;59;64;57 16:20:04 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;69;69;70;68;70;71;69;69 16:20:04 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;84;84;85;87;85;85;88;85 16:20:04 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;119;115;116;123;116;125;115;119;115 16:20:04 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;142;144;148;161;144;149;148;148;137 16:20:05 INFO - 2015-11-20 16:20:04,997 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;67;66;66;66;67;66;65;65;65 16:20:05 INFO - 2015-11-20 16:20:04,998 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:20:05 INFO - 2015-11-20 16:20:04,998 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:20:05 INFO - 2015-11-20 16:20:04,998 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,918 16:20:05 INFO - 2015-11-20 16:20:04,998 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:20:05 INFO - 2015-11-20 16:20:04,998 DEBUG : BROWSER_OUTPUT: __startTimestamp1448065204993__endTimestamp 16:20:05 INFO - 2015-11-20 16:20:04,998 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:04,999 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:20:05 INFO - 2015-11-20 16:20:04,999 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:20:05 INFO - 2015-11-20 16:20:04,999 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:04,999 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.70 Median:110.50 stddev:4.52 (4.1%) stddev-sans-first:4.79 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: Values: 108.0 113.0 103.0 110.0 110.0 110.0 99.0 111.0 114.0 109.0 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.40 Median:151.00 stddev:14.05 (9.3%) stddev-sans-first:14.47 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: Values: 155.0 133.0 152.0 161.0 140.0 150.0 149.0 126.0 164.0 124.0 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.00 Median:161.50 stddev:3.74 (2.3%) stddev-sans-first:3.95 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 162.0 172.0 161.0 159.0 160.0 163.0 159.0 161.0 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.90 Median:72.50 stddev:19.36 (26.7%) stddev-sans-first:20.13 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 85.0 95.0 70.0 71.0 71.0 132.0 88.0 72.0 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.40 Median:90.00 stddev:22.09 (24.5%) stddev-sans-first:22.81 16:20:05 INFO - 2015-11-20 16:20:05,000 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 134.0 90.0 90.0 90.0 139.0 90.0 90.0 133.0 16:20:05 INFO - 2015-11-20 16:20:05,001 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,001 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:20:05 INFO - 2015-11-20 16:20:05,001 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 109.0 109.0 110.0 109.0 109.0 110.0 16:20:05 INFO - 2015-11-20 16:20:05,001 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,002 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 16:20:05 INFO - 2015-11-20 16:20:05,002 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 145.0 16:20:05 INFO - 2015-11-20 16:20:05,002 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,002 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.70 Median:58.50 stddev:2.11 (3.6%) stddev-sans-first:2.15 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: Values: 57.0 58.0 58.0 57.0 60.0 59.0 58.0 59.0 64.0 57.0 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 69.0 70.0 68.0 70.0 71.0 69.0 69.0 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:1.32 (1.5%) stddev-sans-first:1.32 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 84.0 84.0 85.0 87.0 85.0 85.0 88.0 85.0 16:20:05 INFO - 2015-11-20 16:20:05,003 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.60 Median:119.00 stddev:3.84 (3.2%) stddev-sans-first:3.72 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: Values: 123.0 119.0 115.0 116.0 123.0 116.0 125.0 115.0 119.0 115.0 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.50 Median:148.00 stddev:6.26 (4.2%) stddev-sans-first:6.57 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: Values: 144.0 142.0 144.0 148.0 161.0 144.0 149.0 148.0 148.0 137.0 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 66.0 66.0 66.0 67.0 66.0 65.0 65.0 65.0 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:20:05 INFO - 2015-11-20 16:20:05,004 DEBUG : BROWSER_OUTPUT: 16:20:05 INFO - 2015-11-20 16:20:05,854 INFO : Browser exited with error code: 0 16:20:05 INFO - 2015-11-20 16:20:05,910 INFO : Completed test kraken (00:00:58) 16:20:05 INFO - 2015-11-20 16:20:05,910 INFO : Starting test v8_7 16:20:05 INFO - 2015-11-20 16:20:05,910 DEBUG : operating with platform_type : linux_ 16:20:05 INFO - 2015-11-20 16:20:05,910 INFO : Initialising browser for v8_7 test... 16:20:05 INFO - 2015-11-20 16:20:05,916 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQhI2Mi/profile http://localhost:55635/getInfo.html 16:20:09 INFO - 2015-11-20 16:20:09,771 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:20:09 INFO - 2015-11-20 16:20:09,771 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:20:09 INFO - 2015-11-20 16:20:09,771 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:20:09 INFO - 2015-11-20 16:20:09,771 DEBUG : BROWSER_OUTPUT: __metrics 16:20:09 INFO - 2015-11-20 16:20:09,916 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:11 INFO - 2015-11-20 16:20:11,135 INFO : Browser initialized. 16:20:11 INFO - 2015-11-20 16:20:11,136 INFO : Running cycle 1/1 for v8_7 test... 16:20:11 INFO - 2015-11-20 16:20:11,136 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQhI2Mi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:20:22 INFO - 2015-11-20 16:20:22,954 DEBUG : BROWSER_OUTPUT: RSS: Main: 140197888 16:20:22 INFO - 2015-11-20 16:20:22,954 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55635/tests/v8_7/run.html (next: http://localhost:55635/tests/v8_7/run.html) 16:20:44 INFO - 2015-11-20 16:20:44,633 DEBUG : BROWSER_OUTPUT: RSS: Main: 624676864 16:20:44 INFO - 2015-11-20 16:20:44,633 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: |0;Richards;162.97262059973923 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.07598269700958 16:20:44 INFO - 2015-11-20 16:20:44,635 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.135788585887 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5040.201005025126 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1966.6011787819255 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |5;Earley;775.1937984496124 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |6;Boyer;12144.578313253012 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |7;RegExp;25125 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |8;Splay;651.9480519480519 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: __startTimestamp1448065244634__endTimestamp 16:20:44 INFO - 2015-11-20 16:20:44,636 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.97 Median:162.97 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: Values: 163.0 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.08 Median:188.08 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: Values: 188.1 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.14 Median:281.14 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: Values: 281.1 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,638 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5040.20 Median:5040.20 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: Values: 5040.2 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1966.60 Median:1966.60 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: Values: 1966.6 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:775.19 Median:775.19 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: Values: 775.2 16:20:44 INFO - 2015-11-20 16:20:44,639 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12144.58 Median:12144.58 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: Values: 12144.6 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25125.00 Median:25125.00 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: Values: 25125.0 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,640 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:651.95 Median:651.95 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,641 DEBUG : BROWSER_OUTPUT: Values: 651.9 16:20:44 INFO - 2015-11-20 16:20:44,641 DEBUG : BROWSER_OUTPUT: 16:20:44 INFO - 2015-11-20 16:20:44,641 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 16:20:44 INFO - 2015-11-20 16:20:44,641 DEBUG : BROWSER_OUTPUT: Values: 4840.6 16:20:44 INFO - 2015-11-20 16:20:44,641 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:20:44 INFO - 2015-11-20 16:20:44,641 DEBUG : BROWSER_OUTPUT: 16:20:45 INFO - 2015-11-20 16:20:45,418 INFO : Browser exited with error code: 0 16:20:45 INFO - 2015-11-20 16:20:45,430 INFO : Completed test v8_7 (00:00:39) 16:20:45 INFO - 2015-11-20 16:20:45,625 INFO : Completed test suite (00:20:12) 16:20:45 INFO - 2015-11-20 16:20:45,625 DEBUG : Working with test: dromaeo_css 16:20:45 INFO - 2015-11-20 16:20:45,625 DEBUG : Generating results file: dromaeo_css 16:20:45 INFO - 2015-11-20 16:20:45,626 DEBUG : Working with test: kraken 16:20:45 INFO - 2015-11-20 16:20:45,626 DEBUG : Generating results file: kraken 16:20:45 INFO - 2015-11-20 16:20:45,627 INFO : javascript benchmark 16:20:45 INFO - 2015-11-20 16:20:45,627 DEBUG : Working with test: v8_7 16:20:45 INFO - 2015-11-20 16:20:45,627 DEBUG : Generating results file: v8_7 16:20:45 INFO - 2015-11-20 16:20:45,627 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:20:45 INFO - 2015-11-20 16:20:45,689 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 16:20:45 INFO - 2015-11-20 16:20:45,690 DEBUG : process_Request line: dromaeo_css 6977.00 graph.html#tests=[[72,64,35]] 16:20:45 INFO - 2015-11-20 16:20:45,690 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:20:45 INFO - 2015-11-20 16:20:45,801 DEBUG : process_Request line: kraken 1497.50 graph.html#tests=[[232,64,35]] 16:20:45 INFO - 2015-11-20 16:20:45,801 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:20:45 INFO - 2015-11-20 16:20:45,923 DEBUG : process_Request line: v8_7 19902.29 graph.html#tests=[[230,64,35]] 16:20:45 INFO - 2015-11-20 16:20:45,925 INFO : javascript benchmark 16:20:45 INFO - 2015-11-20 16:20:45,928 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3154.8107113573183, 3239.206394433364, 3273.852676629553, 3281.5077738339246, 3291.7860339077965, 5173.974130129353, 5291.126964050927, 5294.179402192657, 5431.72841357932, 5487.7530511127, 5301.0, 5345.599080068991, 5398.6220964532495, 5411.594130440213, 5412.485813847688, 4912.852614421564, 4987.251912213179, 4993.775280112403, 5007.4491805901325, 5014.272930425104, 4872.342233798445, 5049.065922804275, 5072.9492705073035, 5084.79660813567, 5128.666636668623, 4839.370881785363, 4923.458419573844, 4929.901401971941, 4936.259561065833, 4952.925706114411, 4955.40535135786, 4961.429435614915, 4983.352164218664, 4985.5513003829465, 5031.144705400091, 4730.337752714623, 4825.034993001379, 4929.186684197103, 4936.703797772109, 4980.12847751642, 5096.490350964911, 5161.638685292031, 5161.8193363232485, 5175.482451754832, 5178.352705911761, 5778.884422311572, 5779.653220806765, 5784.797532086357, 5800.4489573490455, 5828.2131912191735, 5759.107338362561, 5829.650220986754, 5829.825105246807, 5900.055991041413, 5907.438793314628, 5308.124159513675, 5402.810901618424, 5413.296271484761, 5421.484958928935, 5467.507924286793, 5855.970720146372, 5877.500412464903, 5880.411958804171, 5885.323187833368, 5915.3788852170765, 5527.55779537636, 5659.377468478464, 5662.37713851476, 5715.342735585461, 5727.198192253088, 5803.216565763568, 5884.205632239678, 5884.558658100574, 5894.056950887837, 5940.287165540163, 5813.651180929073, 5866.970665146647, 5873.295204575478, 5879.559033072536, 5882.147088672119, 5532.17017447378, 5658.886822263614, 5714.200011998323, 5870.3249126350165, 5875.823725288248, 4495.505494395614, 4526.886827829265, 4529.886752831139, 4556.498785133633, 4566.26939689648, 4018.1160144768105, 4018.3771515415156, 4023.839046438168, 4029.0128918415176, 4067.491563554556, 5366.356037275552, 5445.482679145475, 5457.781688732406, 5577.776888924479, 5637.408072152365, 5740.741666625012, 5742.167385729046, 5743.195952566644, 5743.885122297531, 5780.277465316835, 5434.809781657702, 5441.428649991773, 5443.646162999472, 5443.7822487100075, 5454.318210223722, 4908.950910490921, 5028.069807085701, 5056.873578160502, 5139.897202056013, 5181.455947125498, 5988.9401105989255, 5990.760369585255, 5993.190919226023, 6000.789972350947, 6013.368596297326, 5782.132680098019, 5802.65184088956, 5822.650640961556, 5833.82498525045, 5855.180274761452, 5765.827025189251, 5926.4666180044005, 5970.910436343372, 5973.223480947623, 6058.212432383762, 5795.623284486495, 5824.854378640568, 5836.854078647898, 5852.531797456279, 5853.678047707417, 5910.0, 5933.851653708692, 5937.9406205937385, 5945.316288626863, 5945.881082378241, 5529.115341545334, 5534.363548192009, 5624.8875022498505, 5636.802711905064, 5659.349174844946, 5740.225069615715, 5742.138679197987, 5754.6547207167705, 5761.251037365283, 5778.913316300343, 5426.294581704511, 5616.494515493625, 5628.071368224197, 5637.46444087811, 5651.463111004448, 5543.584231182596, 5585.832425027255, 5676.5742569308295, 5682.318121825242, 5737.569682273763, 5522.696251706034, 5530.336359636869, 5532.28080349552, 5551.278333816578, 5639.8590035249445], "unit": "ms", "name": "dojo.html", "value": 5355.132318762826}, {"lowerIsBetter": false, "replicates": [561.5389045671832, 584.9912251316229, 585.2713371852045, 604.9183360246367, 609.4332270987979, 31545.369092618133, 32293.51559726606, 32393.83803080987, 32423.83788081059, 32429.67570324299, 41070.0, 41431.378529322086, 41434.792826035904, 41486.17027659445, 41512.79243603778, 8735.606897689588, 8878.200961913426, 8900.37697361185, 8906.554672266393, 8911.064338244489, 7902.841943161134, 8089.514629122242, 8686.826263474726, 8805.20753132218, 10343.069123778858, 10465.529051192714, 10576.78846423071, 10577.682669519889, 10578.047975682188, 10579.682609521727, 10456.999999999962, 10551.10315623173, 10574.312669676447, 10577.471126443725, 10580.788384232272, 7883.2905038546805, 8089.47418417801, 8098.838023239561, 10450.111740502067, 10759.56961721538, 38829.61170388288, 39300.60699392998, 39304.60695393038, 39315.80342098271, 39366.40950385747, 3034.5903303054247, 3085.2903832118736, 3087.81473111614, 3161.44674681932, 3411.1642647551507, 29390.559141612892, 29657.555136672967, 29902.0, 30121.548176777367, 30780.0, 3746.831392587313, 3852.2295540891655, 3880.1269714314335, 3903.902402439962, 3963.093015308564, 280984.0, 281581.0, 284097.9999999979, 284151.0, 284477.0, 55725.44274557284, 56667.0, 56988.14517782236, 57003.14495282574, 57089.71455142698, 66599.0, 67407.66296168488, 67860.0, 68027.0, 68088.6595567019, 14698.632534186518, 14869.256537173098, 14901.329440175217, 14907.105573665614, 14907.70184596302, 12211.450484728208, 12386.0, 12390.132690711564, 12391.0, 12397.566085186974, 19044.33344832952, 19280.517987050436, 19368.612627747367, 19374.70937935932, 19405.611887762447, 7508.361789247975, 7715.807104822312, 7726.922730772732, 7745.806354841061, 7766.689332426639, 931.8276118918022, 976.272676855747, 994.5623925472769, 1036.6889933020063, 1048.695878195315, 27626.447471050466, 27912.581311280348, 28178.71821281802, 28310.43379132406, 28421.43157136887, 1050.81610718124, 1071.3893080943942, 1084.6529110684505, 1146.633077415236, 1156.4795841871191, 14600.780988284972, 14894.627634309229, 14964.0, 15111.168885711391, 15200.847991519944, 307737.0, 312468.0, 313045.0, 313288.0, 313357.0, 51592.4203789809, 52296.738516307174, 52311.21533177005, 52369.21446178311, 52385.47614523806, 1323.9139455935333, 1328.1499840102342, 1328.6678330417203, 1330.2949436798513, 1350.547566565192, 25154.496910062062, 26905.865470673303, 27788.583171252852, 29013.56479652849, 29413.26466838346, 43202.13595728131, 43288.13423731571, 43618.0, 43732.99999999873, 43825.0, 271077.0, 274646.0, 275014.0, 275247.0, 283408.0, 26247.21258362252, 31667.84166079155, 31823.68176318207, 31918.0, 32163.195920101247, 39679.801600991814, 42675.0, 42838.35742463928, 43702.56297437112, 43904.34143487914, 35902.0, 36057.27885442224, 36347.27305453823, 36399.45203835812, 36748.26503469969, 35638.465423018155, 35703.82148089243, 36522.65679056568, 36956.63043369532, 36998.0, 35377.64622353847, 35794.28411431809, 36198.63801361953, 36203.81898090493, 36237.45643815292, 30945.381092378477, 31349.0, 33237.833810830794, 33325.66674333226, 33332.500012499346, 24462.877685611456, 24513.387165321008, 24612.261632151065, 24726.258212253662, 25038.874805625856], "unit": "ms", "name": "ext.html", "value": 18523.006832203002}, {"lowerIsBetter": false, "replicates": [360.4269211952995, 360.50430657845465, 361.8425984696657, 363.4348587945745, 365.26763838503797, 13631.591052268423, 13816.101953373023, 16441.671166576678, 16760.0, 17167.48497545074, 15712.528624141294, 15768.0, 16589.41937032204, 16727.247273872676, 16729.414470493535, 4653.976125252448, 4817.156997525437, 4823.397075365579, 4823.5176482351835, 4830.565249127578, 3677.411614141738, 4750.524947505239, 4768.308595253703, 5675.858103547403, 6006.609570377933, 5941.316748573904, 5943.01940179872, 5965.522758179357, 5998.580099393044, 6006.699665016754, 5097.23541468779, 5189.766460509286, 5205.0, 5228.189630607243, 5230.92153617696, 4934.481879402683, 5006.924896126561, 5013.548780609726, 5189.403218629868, 5194.92207616886, 19358.612827743367, 19744.40766776999, 20033.8998305009, 20771.68842467364, 20805.271815486387, 1961.18610776529, 2056.7840376760373, 2056.9177232910665, 2099.548597051641, 2129.2015494189677, 9514.952425237829, 10867.0, 12266.509339626404, 12787.104902656818, 13291.86708132916, 1736.053850651398, 1812.5831058856404, 1832.1480511562188, 1860.7115897035847, 1866.1975350599237, 60960.390396095914, 64367.3563264366, 64781.0, 64930.0, 65742.3425765741, 9628.133467987915, 9629.903700962901, 9693.466859322663, 9814.0, 10038.498075096217, 12129.939350303368, 12209.084318676134, 12209.755804883853, 12231.266124032587, 12251.632451026484, 7580.431467639949, 7622.9237707622215, 7780.416468764865, 7793.06483222017, 7834.0, 7742.0, 7763.534187948741, 7791.766247012599, 7830.138684744674, 7832.0601527817025, 4019.9196016079513, 4052.371882358239, 4069.5116586009276, 4200.894977625584, 4296.076343586144, 1630.2663801289468, 1693.7459381092935, 1769.3364988129451, 1773.272958087178, 1790.579213884736, 913.3652111782247, 938.5682586010375, 938.9577469013774, 1047.2250534604339, 1056.265895202842, 16596.58508537296, 16921.407750728664, 17094.743578846326, 17288.394906178222, 17369.56576085608, 947.924166066713, 953.5899563187758, 967.8112768010311, 1028.753099256173, 1030.340582027503, 9508.809823803624, 9820.263480239011, 9845.458499782437, 9864.506774661239, 9943.850842237372, 349865.0, 354030.0, 375183.0, 506053.0, 522100.0, 41893.0, 66859.66570167118, 69166.0, 77182.6140869292, 77844.68843234243, 1082.8971247731454, 1093.7429704019548, 1104.9944750276197, 1109.328856042099, 1112.8998390144925, 14993.700125997637, 15301.540953771404, 15495.45765898188, 15788.526344209693, 15924.522264332089, 32767.0, 32983.73415826745, 32988.67011329856, 35727.46408803922, 36654.0, 43137.78431107824, 45785.0, 46690.766546167055, 47169.76415117903, 49101.0, 9142.0, 9195.632174712937, 9313.953430233076, 9348.392354496937, 9570.760730981781, 17281.827181728524, 17340.30638774435, 17376.218070187126, 17656.73514897252, 17730.202140903948, 14628.122312661275, 14741.262936853329, 14761.778573321624, 14845.703085938438, 15242.161681107646, 14175.787363189354, 14244.359003844645, 14350.784738228725, 14612.0, 14992.775108373165, 14605.634859128606, 14925.0, 14985.850141498446, 15096.54710358691, 15328.770068448759, 9341.766455838386, 9352.766180845534, 9355.532223388667, 9473.431594104375, 9545.043266130566, 11432.371219583003, 14690.706185876435, 14741.55775326742, 14973.401063957319, 15091.471798487], "unit": "ms", "name": "jquery.html", "value": 8764.361377183857}, {"lowerIsBetter": false, "replicates": [1328.4686125549786, 1414.3988804757978, 1483.3176738700197, 1599.3122957128437, 1767.0634563681244, 6817.6932038058285, 7531.058617672791, 7838.294553490192, 7843.333316668076, 7846.921530784704, 7654.808629784258, 7718.6912523498995, 7784.727534536292, 7790.571518566477, 7795.18150594188, 1990.2138655231247, 2056.0644906567477, 2060.5878824235137, 2263.54729054189, 2289.198780426854, 2296.5866144094057, 2331.40549159964, 2333.0434521846046, 2334.5681049005902, 2336.5910965581043, 2238.55228954209, 2245.92195746042, 2306.792388685018, 2306.8615883047073, 2310.1221535816367, 1953.9706904396373, 2011.1251605551631, 2014.9496262593407, 2015.6371853066441, 2242.932712018642, 2241.2043724477903, 2302.70064891563, 2304.2626359564947, 2306.815454763615, 2308.907643694267, 7622.8475430491635, 7768.805779855492, 7812.53124812513, 7813.335866451358, 7820.648070836826, 4669.626429885601, 4736.976315118437, 4739.691920025188, 4758.214894542396, 4771.427428708543, 3045.162580290416, 3061.785675002751, 3089.9845500772576, 3107.5027995520836, 3108.036508682316, 995.7311525888042, 1003.5433877585681, 1005.1606908231636, 1008.5209525475341, 1042.9113525350353, 10833.566657333697, 10847.349159050482, 10862.34825910448, 10875.564977400894, 10924.563017479291, 4317.762523061199, 4335.3713711511655, 4376.212281789245, 4378.190034843564, 4437.955620443819, 9255.537223138816, 9261.44431334122, 9264.351495395393, 9270.860937085949, 9281.95359023214, 4740.905181896412, 4759.143354196278, 4775.5224477551965, 4789.185838407479, 4792.161371759928, 4342.5657434256955, 4384.386185933972, 4390.297552391666, 4394.055278115221, 4397.252467080604, 3698.6856117230336, 3718.8326525306425, 3740.401535754266, 3740.5698344690163, 3757.079515518715, 1247.5134697467995, 1261.369315342329, 1265.6139877337316, 1268.5623459906317, 1270.4664041102758, 1224.4612370556927, 1232.6671798614325, 1253.179167645194, 1265.1966001589058, 1265.2851139106376, 7102.786916392516, 7194.136703595497, 7198.0, 7207.279272072751, 7216.386607138452, 475.5007242395471, 476.8283417969528, 481.79764498910544, 482.16103979076155, 498.22277247494026, 5471.6169868108855, 5562.3047119110115, 5572.86067848307, 5638.718064096779, 5680.204771332017, 9262.073792620684, 9376.203022743006, 9519.524023798782, 9574.0, 9592.760180995392, 9038.367314287936, 9187.63249470027, 9305.348625596143, 9335.253179745605, 9365.484898330657, 6934.4452443804375, 6992.265812089658, 7003.124609423822, 7054.6472676365975, 7126.00235966965, 6741.662916854236, 6991.685374158277, 7031.191412987572, 7044.049053377728, 7089.680964356513, 8074.152214017444, 8479.279261262862, 8480.10958849312, 8510.446820956618, 8623.310135189295, 8534.70128545498, 8690.91309086901, 8703.782405439915, 8893.64425422976, 8895.06601806801, 6878.931210687829, 6898.655067246718, 6916.23921368639, 6928.0, 6930.757423490154, 7326.120865496175, 7511.737089201852, 7533.058367704037, 7567.129780075362, 7567.697292108474, 6833.726650933907, 6951.895721564079, 7084.752033679003, 7089.858202835811, 7135.964320178366, 6756.932430675827, 6770.187577490733, 6824.7952561423235, 6849.48628852848, 6859.897101543581, 7010.0, 7041.718331266692, 7045.718171273296, 7051.682674279567, 7052.612106334201, 6135.938640613536, 6247.656378899204, 6257.374262573889, 6263.937360626335, 6268.059791031382, 6147.231596050494, 6177.62934223948, 6184.350643182401, 6187.102870083722, 6193.783217587363], "unit": "ms", "name": "mootools.html", "value": 4377.537320716019}, {"lowerIsBetter": false, "replicates": [311.0962653491608, 314.4418656884029, 333.3682671337815, 338.8373580681273, 340.6219096802549, 5848.356680765112, 6020.698965051746, 6037.788677396292, 6110.388961103888, 6122.387761223876, 5806.680632565218, 5953.821385358447, 5976.3724808895095, 5976.820695379135, 5989.490893274066, 4635.212013957627, 4639.907201855961, 4642.605378542828, 4646.954435252075, 4649.232876575361, 4457.576530229623, 4564.087182563484, 4571.862844114666, 4629.606483448894, 4646.512116227798, 4579.862604121865, 4582.839600613979, 4594.724316541002, 4600.608948239387, 4617.468991066037, 4572.062727140945, 4584.564466375689, 4596.724196548184, 4600.9769951150365, 4607.239805432099, 4044.5955404459614, 4051.169510250392, 4053.716239863211, 4060.309747342959, 4075.8981025474304, 5583.05088135018, 5684.602077854552, 5685.374608793029, 5685.8009969651275, 5688.11834165705, 5363.731813409314, 5368.91946620801, 5369.167778994262, 5374.7850085996515, 5380.085385484477, 6089.330173680892, 6308.148399965991, 6334.113224148623, 6342.175517182736, 6347.206599175103, 4376.781160941972, 4393.758343291118, 4481.484629267643, 4483.461984561874, 4506.121306345264, 6594.53838231324, 6634.966825165891, 6644.700988455531, 6652.0354548590185, 6656.36764507371, 4617.822455273884, 4622.191116554657, 4625.121226966866, 4625.444946606429, 4626.629869610423, 4388.8902777430185, 4456.46522417312, 4508.504064552896, 4679.64902632304, 4683.531646835358, 5205.5835533158015, 5211.009908117446, 5248.396434410015, 5265.631405801557, 5266.710330931835, 6425.614463132227, 6465.870682586322, 6472.708728107153, 6474.12599299098, 6477.1579694639395, 1605.7109720250467, 1615.4023011485826, 1616.2322896624244, 1616.9919150404173, 1618.902865828054, 1055.4458909072798, 1066.1151244467073, 1067.1036329483272, 1095.05277934586, 1097.4567589043315, 6231.719572619243, 6237.656928868865, 6238.781642642486, 6261.812145635638, 6266.937330626635, 6671.532992690562, 6671.666416679147, 6678.23200331968, 6685.699143538553, 6686.699098540577, 6061.121137435047, 6207.751689932352, 6221.3156552779155, 6228.190335256388, 6242.687865606701, 3570.071781336819, 3595.7123430125525, 3597.5323207982797, 3598.586162591283, 3602.189507360823, 6844.760433384808, 6844.794656160323, 6844.965775171092, 6845.349691779273, 6847.486438517031, 6647.734090636321, 6762.763303284361, 6772.984052392082, 6798.694058767367, 6804.455643548505, 6826.795196144124, 6827.0783444235385, 6829.146356705412, 6829.795106146724, 6835.58986460814, 6428.8071357859335, 6568.671566421564, 6596.934030659632, 6607.075009498578, 6619.569727967667, 6583.177102862142, 6583.835404114935, 6632.204135503771, 6638.5684930479365, 6640.103586015826, 6870.553414028072, 6889.896651550331, 6891.827704307432, 6892.655367231719, 6901.723931042702, 4387.166438376698, 4488.775561221991, 4513.842015529572, 4523.0953809238945, 4530.161920044802, 6621.106150669598, 6622.304658010839, 6627.5360724750735, 6634.0, 6636.037774522763, 6437.742490300336, 6443.387878151568, 6444.742210311535, 6447.2263328400895, 6448.355164483514, 6315.115883776183, 6433.067205255305, 6433.453156481752, 6476.870462590816, 6511.120998665119, 6375.458086062737, 6376.171097757262, 6377.043443483514, 6383.680815959091, 6393.6483493408305, 6542.640154791532, 6546.279909209888, 6548.148740663683, 6553.5084868634085, 6555.180602424697, 6186.1030150627, 6316.273628532594, 6333.429991300805, 6337.651429171256, 6350.047492876106], "unit": "ms", "name": "prototype.html", "value": 4832.206398273567}, {"lowerIsBetter": false, "replicates": [833.8374017066675, 837.2297486312592, 840.3949156607242, 849.2229609906935, 849.9575021248938, 12258.877411225885, 12499.500019999188, 12503.562375316866, 12507.49970001199, 12512.49950002, 10794.406307653077, 11794.0, 11842.881571184285, 11873.406329683503, 11873.584424545143, 665.3446355339986, 795.129333379948, 818.8239528501371, 819.7499762572413, 819.8770184472318, 802.5987006496752, 805.8267472493412, 807.7011505742884, 809.7894547417684, 813.2396209544065, 685.1367277230676, 693.8404167041583, 833.3583140981441, 839.7438781171741, 840.7393707950555, 844.2275318083946, 859.8753180788813, 859.9398042137053, 860.182826314997, 861.8147098373848, 625.4027403829332, 628.1614045249597, 630.0423356498097, 630.3822254190873, 633.2527617411453, 11869.52521899123, 11921.88078119225, 12021.939890300493, 12051.638450846402, 12065.155439119266, 11930.22453540517, 11975.64073077809, 12006.399680015964, 12243.081768867281, 12354.938225308906, 9853.90146098537, 9989.950050249703, 10049.145822605087, 10068.446235457048, 10161.136303414218, 3587.157018100744, 3602.7297952653657, 3608.9097772555633, 3624.5469316335457, 3718.1262403335463, 47736.0, 47852.7607361961, 47986.0, 49619.25571116436, 49869.501304987214, 9901.0, 9982.450965196838, 9985.30102892805, 10034.899651003396, 10094.49527523621, 19044.71432928507, 19072.61854762897, 19074.809251907303, 19075.046247687562, 19077.904610477137, 6931.029655848185, 6970.965145174242, 7213.531120477152, 7271.745488907863, 7391.2978267064545, 4244.745315281093, 4324.870253892325, 4355.324924636687, 4370.2352088384405, 4378.824847006084, 2960.6003189569133, 2960.6743258241577, 2973.9702602973694, 2986.1190948670196, 2991.3718119195214, 538.1148011521026, 544.6242092955848, 546.1643685161708, 546.9316335458068, 550.5182964905707, 610.6427739772195, 615.9815205543841, 661.256086902235, 667.519386042049, 690.7513295213719, 8493.745187644381, 8631.309495240366, 8647.264982476434, 8804.251638610662, 8808.735737927873, 635.4820821030845, 657.4062156569614, 676.0569006236273, 678.5521555773166, 679.6771533521538, 3992.8203230854683, 4097.672186225095, 4121.340585506365, 4163.791810409467, 4167.432982567746, 55925.72037139788, 56256.0, 56461.7176914121, 56476.71761641165, 57167.42832571704, 49863.75068124636, 50949.490505095215, 51251.743741281054, 51367.22949155766, 51508.0, 46027.76986115115, 46315.0, 46684.832879177615, 46709.29936050962, 47137.292940605235, 7797.532148071134, 7968.282854543119, 8187.426880118573, 8935.821283574422, 9174.036726143659, 35631.10922226965, 35839.46240806338, 35930.8203458981, 37047.25905481929, 37659.43510847285, 40483.39274910938, 42128.57871421369, 42586.78706606447, 44393.55606443894, 45113.32330014987, 11087.22389432732, 11182.944085279521, 11273.0, 11309.830352544883, 11504.769904602028, 12597.937010315256, 12818.102732808615, 12819.935900320812, 12959.740805184032, 13028.869711302767, 11219.943900280447, 11529.769404611692, 11640.417979101181, 11643.53425862956, 11997.82003269969, 11677.870302027539, 12122.636320910387, 12174.939125304316, 12257.754844903231, 12636.810447843474, 11781.116416268642, 11823.940880295544, 11837.526498939946, 12000.279983201035, 12207.0, 9880.752981175528, 10006.649767258403, 10231.334963227366, 10396.584136634752, 10452.581896724047, 8786.341024423322, 9032.954835225783, 9506.762330941781, 9538.475383853955, 9774.12032917041], "unit": "ms", "name": "yui.html", "value": 6271.871378540244}], "name": "dromaeo_css", "value": 6977.002799036869}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 113.0, 103.0, 110.0, 110.0, 110.0, 99.0, 111.0, 114.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.7}, {"lowerIsBetter": true, "replicates": [155.0, 133.0, 152.0, 161.0, 140.0, 150.0, 149.0, 126.0, 164.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.4}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 172.0, 161.0, 159.0, 160.0, 163.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 162.0}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 85.0, 95.0, 70.0, 71.0, 71.0, 132.0, 88.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 82.9}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 134.0, 90.0, 90.0, 90.0, 139.0, 90.0, 90.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 103.4}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 58.0, 57.0, 60.0, 59.0, 58.0, 59.0, 64.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 58.7}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 69.0, 70.0, 68.0, 70.0, 71.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 84.0, 84.0, 85.0, 87.0, 85.0, 85.0, 88.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.2}, {"lowerIsBetter": true, "replicates": [123.0, 119.0, 115.0, 116.0, 123.0, 116.0, 125.0, 115.0, 119.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.6}, {"lowerIsBetter": true, "replicates": [144.0, 142.0, 144.0, 148.0, 161.0, 144.0, 149.0, 148.0, 148.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.5}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.0}], "name": "kraken", "value": 1497.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.97262059973923], "unit": "score", "name": "Richards", "value": 216.61307199999993}, {"lowerIsBetter": false, "replicates": [188.07598269700958], "unit": "score", "name": "DeltaBlue", "value": 351.549406}, {"lowerIsBetter": false, "replicates": [281.135788585887], "unit": "score", "name": "Encrypt", "value": 946.8058169999996}, {"lowerIsBetter": false, "replicates": [5040.201005025126], "unit": "score", "name": "Decrypt", "value": 52.81158424725827}, {"lowerIsBetter": false, "replicates": [1966.6011787819255], "unit": "score", "name": "RayTrace", "value": 376.2781228771229}, {"lowerIsBetter": false, "replicates": [775.1937984496124], "unit": "score", "name": "Earley", "value": 859.7372699999999}, {"lowerIsBetter": false, "replicates": [12144.578313253012], "unit": "score", "name": "Boyer", "value": 54.87740972222223}, {"lowerIsBetter": false, "replicates": [25125.0], "unit": "score", "name": "RegExp", "value": 36.25810945273629}, {"lowerIsBetter": false, "replicates": [651.9480519480519], "unit": "score", "name": "Splay", "value": 124.99615537848611}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 19902.291589316308}]} 16:20:45 INFO - RETURN: dromaeo_css: 6977.00 16:20:45 INFO - RETURN: kraken: 1497.50 16:20:45 INFO - RETURN: v8_7: 19902.29 16:20:45 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19902.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1497.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6977.00"}}} 16:20:46 INFO - Return code: 0 16:20:46 INFO - # TBPL SUCCESS # 16:20:46 INFO - Running post-action listener: _resource_record_post_action 16:20:46 INFO - Running post-run listener: _resource_record_post_run 16:20:47 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 4096; Write bytes: 505266176; Read time: 196; Write time: 566092 16:20:47 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 130985984; Read time: 0; Write time: 126188 16:20:47 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328404992; Read time: 196; Write time: 408532 16:20:47 INFO - Running post-run listener: _upload_blobber_files 16:20:47 INFO - Blob upload gear active. 16:20:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:20:47 INFO - Copying logs to upload dir... 16:20:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.718468 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-11-20 16:20:46.383575) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 16:20:46.387180) ========= 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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009960 build_url: 'https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/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 16:20:46.438561) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 16:20:46.438878) ========= 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=004b26eee9c3b360c8654f40000001e1-1448063928.424124-370749439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 16:20:46.488677) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 16:20:46.489012) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 16:20:46.489297) ========= ========= Total master_lag: 0.20 =========