builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-064 starttime: 1448063998.17 results: success (0) buildid: 20151120143006 builduid: 3e002eb0799948ec8ceef1f2cc90cba2 revision: b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.170744) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.171254) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.171549) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006458 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.191605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.191941) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.199988) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.200317) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004361 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.249340) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.249689) ========= 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:58.250129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.250455) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-20 15:59:59-- 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% 217M=0s 2015-11-20 15:59:59 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174366 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.437585) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.437901) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016463 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 15:59:58.489346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-20 15:59:58.489770) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-20 15:59:59,286 truncating revision to first 12 chars 2015-11-20 15:59:59,287 Setting DEBUG logging. 2015-11-20 15:59:59,287 attempt 1/10 2015-11-20 15:59:59,287 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 16:00:00,525 unpacking tar archive at: fx-team-b0bdf1bc3b61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.534444 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-20 16:00:00.034297) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 16:00:00.034626) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 16:00:00.044805) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 16:00:00.045279) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 16:00:00.045791) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2015-11-20 16:00:00.046102) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:00:00 INFO - MultiFileLogger online at 20151120 16:00:00 in /builds/slave/test-pgo 16:00:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 16:00:00 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:00:00 INFO - {'append_to_log': False, 16:00:00 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:00:00 INFO - 'blob_upload_branch': 'Fx-Team', 16:00:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:00:00 INFO - 'branch': 'Fx-Team', 16:00:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:00:00 INFO - 'config_files': ('talos/linux_config.py',), 16:00:00 INFO - 'default_actions': ('clobber', 16:00:00 INFO - 'read-buildbot-config', 16:00:00 INFO - 'download-and-extract', 16:00:00 INFO - 'populate-webroot', 16:00:00 INFO - 'create-virtualenv', 16:00:00 INFO - 'install', 16:00:00 INFO - 'run-tests'), 16:00:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:00:00 INFO - 'download_minidump_stackwalk': True, 16:00:00 INFO - 'download_symbols': 'ondemand', 16:00:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:00:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:00:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:00:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:00:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:00:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:00:00 INFO - 'installer_path': 'installer.exe', 16:00:00 INFO - 'log_level': 'info', 16:00:00 INFO - 'log_name': 'talos', 16:00:00 INFO - 'log_to_console': True, 16:00:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:00:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:00:00 INFO - 'opt_config_files': (), 16:00:00 INFO - 'pip_index': False, 16:00:00 INFO - 'sps_profile': False, 16:00:00 INFO - 'sps_profile_interval': 0, 16:00:00 INFO - 'suite': 'dromaeojs-e10s', 16:00:00 INFO - 'system_bits': '64', 16:00:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:00:00 INFO - 'title': 'talos-linux64-ix-064', 16:00:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:00:00 INFO - 'use_talos_json': True, 16:00:00 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:00:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:00:00 INFO - 'work_dir': 'build'} 16:00:00 INFO - ##### 16:00:00 INFO - ##### Running clobber 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: clobber 16:00:00 INFO - rmtree: /builds/slave/test-pgo/build 16:00:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:00:01 INFO - Running post-action listener: _resource_record_post_action 16:00:01 INFO - ##### 16:00:01 INFO - ##### Running read-buildbot-config step. 16:00:01 INFO - ##### 16:00:01 INFO - Running pre-action listener: _resource_record_pre_action 16:00:01 INFO - Running main action method: read_buildbot_config 16:00:01 INFO - Using buildbot properties: 16:00:01 INFO - { 16:00:01 INFO - "properties": { 16:00:01 INFO - "buildnumber": 347, 16:00:01 INFO - "product": "firefox", 16:00:01 INFO - "script_repo_revision": "production", 16:00:01 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 16:00:01 INFO - "repository": "", 16:00:01 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 16:00:01 INFO - "buildid": "20151120143006", 16:00:01 INFO - "slavename": "talos-linux64-ix-064", 16:00:01 INFO - "pgo_build": "True", 16:00:01 INFO - "basedir": "/builds/slave/test-pgo", 16:00:01 INFO - "project": "", 16:00:01 INFO - "platform": "ubuntu64_hw", 16:00:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:00:01 INFO - "slavebuilddir": "test", 16:00:01 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 16:00:01 INFO - "branch": "fx-team", 16:00:01 INFO - "repo_path": "integration/fx-team", 16:00:01 INFO - "stage_platform": "linux64-pgo", 16:00:01 INFO - "builduid": "3e002eb0799948ec8ceef1f2cc90cba2", 16:00:01 INFO - "revision": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73" 16:00:01 INFO - }, 16:00:01 INFO - "sourcestamp": { 16:00:01 INFO - "repository": "", 16:00:01 INFO - "hasPatch": false, 16:00:01 INFO - "project": "", 16:00:01 INFO - "branch": "fx-team-linux64-pgo-talos", 16:00:01 INFO - "changes": [ 16:00:01 INFO - { 16:00:01 INFO - "category": null, 16:00:01 INFO - "files": [ 16:00:01 INFO - { 16:00:01 INFO - "url": null, 16:00:01 INFO - "name": "https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 16:00:01 INFO - } 16:00:01 INFO - ], 16:00:01 INFO - "repository": "", 16:00:01 INFO - "rev": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73", 16:00:01 INFO - "who": "sendchange", 16:00:01 INFO - "when": 1448063911, 16:00:01 INFO - "number": 6691183, 16:00:01 INFO - "comments": "", 16:00:01 INFO - "project": "", 16:00:01 INFO - "at": "Fri 20 Nov 2015 15:58:31", 16:00:01 INFO - "branch": "fx-team-linux64-pgo-talos", 16:00:01 INFO - "revlink": "", 16:00:01 INFO - "properties": [ 16:00:01 INFO - [ 16:00:01 INFO - "buildid", 16:00:01 INFO - "20151120143006", 16:00:01 INFO - "Change" 16:00:01 INFO - ], 16:00:01 INFO - [ 16:00:01 INFO - "builduid", 16:00:01 INFO - "3e002eb0799948ec8ceef1f2cc90cba2", 16:00:01 INFO - "Change" 16:00:01 INFO - ], 16:00:01 INFO - [ 16:00:01 INFO - "pgo_build", 16:00:01 INFO - "True", 16:00:01 INFO - "Change" 16:00:01 INFO - ] 16:00:01 INFO - ], 16:00:01 INFO - "revision": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73" 16:00:01 INFO - } 16:00:01 INFO - ], 16:00:01 INFO - "revision": "b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73" 16:00:01 INFO - } 16:00:01 INFO - } 16:00:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 16:00:01 INFO - Running post-action listener: _resource_record_post_action 16:00:01 INFO - ##### 16:00:01 INFO - ##### Running download-and-extract step. 16:00:01 INFO - ##### 16:00:01 INFO - Running pre-action listener: _resource_record_pre_action 16:00:01 INFO - Running main action method: download_and_extract 16:00:01 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:01 INFO - https://queue.taskcluster.net/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:00:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json 16:00:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kZFlT2AoSxmHCKkkvTrPNQ/artifacts/public/build/test_packages.json 16:00:01 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 16:00:01 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 16:00:01 INFO - Downloaded 1302 bytes. 16:00:01 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:00:01 INFO - Using the following test package requirements: 16:00:01 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:00:01 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:00:01 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'jsshell-linux-x86_64.zip'], 16:00:01 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:00:01 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:00:01 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:00:01 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:00:01 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:00:01 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:00:01 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:00:01 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 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 - 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 16:00:02 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 16:00:02 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 16:00:02 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 16:00:02 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 16:00:03 INFO - Downloaded 22525382 bytes. 16:00:03 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 16:00:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:00:03 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 16:00:04 INFO - Return code: 0 16:00:04 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 16:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:04 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 16:00:04 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 16:00:04 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 16:00:04 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 16:00:04 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 16:00:05 INFO - Downloaded 22525382 bytes. 16:00:05 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 16:00:05 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:00:05 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 16:00:06 INFO - Return code: 0 16:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:06 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 16:00:06 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 16:00:06 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 16:00:06 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 16:00:06 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 16:00:07 INFO - Downloaded 11130997 bytes. 16:00:07 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 16:00:07 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:00:07 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 16:00:07 INFO - Return code: 0 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 - 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 16:00:07 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 16:00:07 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 16:00:07 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 16:00:07 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:09 INFO - Downloaded 57351284 bytes. 16:00:09 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:09 INFO - mkdir: /builds/slave/test-pgo/properties 16:00:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:00:09 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:00:09 INFO - Contents: 16:00:09 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:09 INFO - Running post-action listener: _resource_record_post_action 16:00:09 INFO - Running post-action listener: set_extra_try_arguments 16:00:09 INFO - ##### 16:00:09 INFO - ##### Running populate-webroot step. 16:00:09 INFO - ##### 16:00:09 INFO - Running pre-action listener: _resource_record_pre_action 16:00:09 INFO - Running main action method: populate_webroot 16:00:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:09 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:00:09 INFO - '../fennec_ids.txt'], 16:00:09 INFO - 'tests': ['tcheck2']}, 16:00:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:09 INFO - '--tppagecycles', 16:00:09 INFO - '7'], 16:00:09 INFO - 'tests': ['tsvgm']}}, 16:00:09 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:00:09 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:09 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:00:09 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:00:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'tests': ['damp', 'tps']}, 16:00:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['damp', 'tps']}, 16:00:09 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:00:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:09 INFO - 'other': {'tests': ['a11yr', 16:00:09 INFO - 'ts_paint', 16:00:09 INFO - 'tpaint', 16:00:09 INFO - 'sessionrestore', 16:00:09 INFO - 'sessionrestore_no_auto_restore']}, 16:00:09 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['a11yr', 16:00:09 INFO - 'ts_paint', 16:00:09 INFO - 'tpaint', 16:00:09 INFO - 'sessionrestore', 16:00:09 INFO - 'sessionrestore_no_auto_restore']}, 16:00:09 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['a11yr', 16:00:09 INFO - 'ts_paint', 16:00:09 INFO - 'tpaint', 16:00:09 INFO - 'sessionrestore', 16:00:09 INFO - 'sessionrestore_no_auto_restore']}, 16:00:09 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['a11yr', 16:00:09 INFO - 'ts_paint', 16:00:09 INFO - 'tpaint', 16:00:09 INFO - 'sessionrestore', 16:00:09 INFO - 'sessionrestore_no_auto_restore']}, 16:00:09 INFO - 'other_l64': {'tests': ['a11yr', 16:00:09 INFO - 'ts_paint', 16:00:09 INFO - 'tpaint', 16:00:09 INFO - 'sessionrestore', 16:00:09 INFO - 'sessionrestore_no_auto_restore']}, 16:00:09 INFO - 'other_nol64': {'tests': ['a11yr', 16:00:09 INFO - 'ts_paint', 16:00:09 INFO - 'tpaint', 16:00:09 INFO - 'sessionrestore', 16:00:09 INFO - 'sessionrestore_no_auto_restore']}, 16:00:09 INFO - 'svgr': {'tests': ['tsvgx', 16:00:09 INFO - 'tsvgr_opacity', 16:00:09 INFO - 'tart', 16:00:09 INFO - 'tscrollx', 16:00:09 INFO - 'cart']}, 16:00:09 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['tsvgx', 16:00:09 INFO - 'tsvgr_opacity', 16:00:09 INFO - 'tart', 16:00:09 INFO - 'tscrollx', 16:00:09 INFO - 'cart']}, 16:00:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'tests': ['tp5o']}, 16:00:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'talos_options': ['--e10s'], 16:00:09 INFO - 'tests': ['tp5o']}, 16:00:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'talos_options': ['--xperf_path', 16:00:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:09 INFO - 'tests': ['tp5n']}, 16:00:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:09 INFO - 'talos_options': ['--e10s', 16:00:09 INFO - '--xperf_path', 16:00:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:09 INFO - 'tests': ['tp5n']}}, 16:00:09 INFO - 'talos.zip': {'path': '', 16:00:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:09 INFO - Running post-action listener: _resource_record_post_action 16:00:09 INFO - ##### 16:00:09 INFO - ##### Running create-virtualenv step. 16:00:09 INFO - ##### 16:00:09 INFO - Running pre-action listener: _resource_record_pre_action 16:00:09 INFO - Running main action method: create_virtualenv 16:00:09 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:00:09 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:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:00:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:09 INFO - Using real prefix '/usr' 16:00:09 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:00:10 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:12 INFO - Installing pip.................done. 16:00:12 INFO - Return code: 0 16:00:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:00:12 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:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:12 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:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:12 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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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:12 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:12 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:12 INFO - Using env: {'DISPLAY': ':0', 16:00:12 INFO - 'HOME': '/home/cltbld', 16:00:12 INFO - 'LANG': 'en_US.UTF-8', 16:00:12 INFO - 'LANGUAGE': 'en_US:en', 16:00:12 INFO - 'LOGNAME': 'cltbld', 16:00:12 INFO - 'MAIL': '/var/mail/cltbld', 16:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:12 INFO - 'MOZ_NO_REMOTE': '1', 16:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:12 INFO - 'NO_EM_RESTART': '1', 16:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:12 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:12 INFO - 'SHELL': '/bin/bash', 16:00:12 INFO - 'SHLVL': '1', 16:00:12 INFO - 'TERM': 'linux', 16:00:12 INFO - 'TMOUT': '86400', 16:00:12 INFO - 'USER': 'cltbld', 16:00:12 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:12 INFO - '_': '/tools/buildbot/bin/python'} 16:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:12 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:12 INFO - Cleaning up... 16:00:12 INFO - Return code: 0 16:00:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:00:12 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:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:12 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:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:12 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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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:12 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:12 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:12 INFO - Using env: {'DISPLAY': ':0', 16:00:12 INFO - 'HOME': '/home/cltbld', 16:00:12 INFO - 'LANG': 'en_US.UTF-8', 16:00:12 INFO - 'LANGUAGE': 'en_US:en', 16:00:12 INFO - 'LOGNAME': 'cltbld', 16:00:12 INFO - 'MAIL': '/var/mail/cltbld', 16:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:12 INFO - 'MOZ_NO_REMOTE': '1', 16:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:12 INFO - 'NO_EM_RESTART': '1', 16:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:12 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:12 INFO - 'SHELL': '/bin/bash', 16:00:12 INFO - 'SHLVL': '1', 16:00:12 INFO - 'TERM': 'linux', 16:00:12 INFO - 'TMOUT': '86400', 16:00:12 INFO - 'USER': 'cltbld', 16:00:12 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:12 INFO - '_': '/tools/buildbot/bin/python'} 16:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:12 INFO - Downloading/unpacking psutil>=0.7.1 16:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:14 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:00:14 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:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:14 INFO - Installing collected packages: psutil 16:00:14 INFO - Running setup.py install for psutil 16:00:14 INFO - building 'psutil._psutil_linux' extension 16:00:14 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:14 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:14 INFO - building 'psutil._psutil_posix' extension 16:00:14 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:14 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:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:15 INFO - Successfully installed psutil 16:00:15 INFO - Cleaning up... 16:00:15 INFO - Return code: 0 16:00:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:00:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:00:15 INFO - Using env: {'DISPLAY': ':0', 16:00:15 INFO - 'HOME': '/home/cltbld', 16:00:15 INFO - 'LANG': 'en_US.UTF-8', 16:00:15 INFO - 'LANGUAGE': 'en_US:en', 16:00:15 INFO - 'LOGNAME': 'cltbld', 16:00:15 INFO - 'MAIL': '/var/mail/cltbld', 16:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:15 INFO - 'MOZ_NO_REMOTE': '1', 16:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:15 INFO - 'NO_EM_RESTART': '1', 16:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:15 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:15 INFO - 'SHELL': '/bin/bash', 16:00:15 INFO - 'SHLVL': '1', 16:00:15 INFO - 'TERM': 'linux', 16:00:15 INFO - 'TMOUT': '86400', 16:00:15 INFO - 'USER': 'cltbld', 16:00:15 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:15 INFO - '_': '/tools/buildbot/bin/python'} 16:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 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:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:00:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:00:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:16 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:16 INFO - Installing collected packages: mozsystemmonitor 16:00:16 INFO - Running setup.py install for mozsystemmonitor 16:00:17 INFO - Successfully installed mozsystemmonitor 16:00:17 INFO - Cleaning up... 16:00:17 INFO - Return code: 0 16:00:17 INFO - Installing blobuploader==1.2.4 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', '--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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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', '--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:17 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: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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 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 - Downloading/unpacking blobuploader==1.2.4 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:18 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:18 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:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00:19 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:19 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:19 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:19 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:19 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:19 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:19 INFO - Downloading docopt-0.6.1.tar.gz 16:00:19 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:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:19 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:19 INFO - Running setup.py install for blobuploader 16:00:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:19 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:00:19 INFO - Running setup.py install for requests 16:00:20 INFO - Running setup.py install for docopt 16:00:20 INFO - Successfully installed blobuploader requests docopt 16:00:20 INFO - Cleaning up... 16:00:20 INFO - Return code: 0 16:00:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:20 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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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:20 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:20 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:20 INFO - Using env: {'DISPLAY': ':0', 16:00:20 INFO - 'HOME': '/home/cltbld', 16:00:20 INFO - 'LANG': 'en_US.UTF-8', 16:00:20 INFO - 'LANGUAGE': 'en_US:en', 16:00:20 INFO - 'LOGNAME': 'cltbld', 16:00:20 INFO - 'MAIL': '/var/mail/cltbld', 16:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:20 INFO - 'MOZ_NO_REMOTE': '1', 16:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:20 INFO - 'NO_EM_RESTART': '1', 16:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:20 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:20 INFO - 'SHELL': '/bin/bash', 16:00:20 INFO - 'SHLVL': '1', 16:00:20 INFO - 'TERM': 'linux', 16:00:20 INFO - 'TMOUT': '86400', 16:00:20 INFO - 'USER': 'cltbld', 16:00:20 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:20 INFO - '_': '/tools/buildbot/bin/python'} 16:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:20 INFO - Running setup.py (path:/tmp/pip-4Q9q6r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:20 INFO - Running setup.py (path:/tmp/pip-S_8vRj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:20 INFO - Running setup.py (path:/tmp/pip-ppQjnT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:20 INFO - Running setup.py (path:/tmp/pip-ul32Yf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:20 INFO - Running setup.py (path:/tmp/pip-MOjvb7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:20 INFO - Running setup.py (path:/tmp/pip-cKteSO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:20 INFO - Running setup.py (path:/tmp/pip-qX343i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:20 INFO - Running setup.py (path:/tmp/pip-PMRvbd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:21 INFO - Running setup.py (path:/tmp/pip-urf0Rq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:21 INFO - Running setup.py (path:/tmp/pip-TRFznV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:21 INFO - Running setup.py (path:/tmp/pip-HnR1CA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:21 INFO - Running setup.py (path:/tmp/pip-I0KDp7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:21 INFO - Running setup.py (path:/tmp/pip-0myfMB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:21 INFO - Running setup.py (path:/tmp/pip-Z7ifax-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:21 INFO - Running setup.py (path:/tmp/pip-QIe7k7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:21 INFO - Running setup.py (path:/tmp/pip-1bwSZf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:21 INFO - Running setup.py (path:/tmp/pip-fXKJfr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:21 INFO - Running setup.py install for manifestparser 16:00:21 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:00:21 INFO - Running setup.py install for mozcrash 16:00:21 INFO - Running setup.py install for mozdebug 16:00:21 INFO - Running setup.py install for mozdevice 16:00:22 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Running setup.py install for mozfile 16:00:22 INFO - Running setup.py install for mozhttpd 16:00:22 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Running setup.py install for mozinfo 16:00:22 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Running setup.py install for mozInstall 16:00:22 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Running setup.py install for mozleak 16:00:22 INFO - Running setup.py install for mozlog 16:00:22 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Running setup.py install for moznetwork 16:00:22 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:00:22 INFO - Running setup.py install for mozprocess 16:00:22 INFO - Running setup.py install for mozprofile 16:00:23 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:00:23 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:00:23 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:00:23 INFO - Running setup.py install for mozrunner 16:00:23 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:00:23 INFO - Running setup.py install for mozscreenshot 16:00:23 INFO - Running setup.py install for moztest 16:00:23 INFO - Running setup.py install for mozversion 16:00:23 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:00:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:23 INFO - Cleaning up... 16:00:23 INFO - Return code: 0 16:00:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:23 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:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 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:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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:23 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:23 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:23 INFO - Using env: {'DISPLAY': ':0', 16:00:23 INFO - 'HOME': '/home/cltbld', 16:00:23 INFO - 'LANG': 'en_US.UTF-8', 16:00:23 INFO - 'LANGUAGE': 'en_US:en', 16:00:23 INFO - 'LOGNAME': 'cltbld', 16:00:23 INFO - 'MAIL': '/var/mail/cltbld', 16:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:23 INFO - 'MOZ_NO_REMOTE': '1', 16:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:23 INFO - 'NO_EM_RESTART': '1', 16:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:23 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:23 INFO - 'SHELL': '/bin/bash', 16:00:23 INFO - 'SHLVL': '1', 16:00:23 INFO - 'TERM': 'linux', 16:00:23 INFO - 'TMOUT': '86400', 16:00:23 INFO - 'USER': 'cltbld', 16:00:23 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:23 INFO - '_': '/tools/buildbot/bin/python'} 16:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:23 INFO - Running setup.py (path:/tmp/pip-X1IBVZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:23 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:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:23 INFO - Running setup.py (path:/tmp/pip-PjjTa_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:23 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:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:23 INFO - Running setup.py (path:/tmp/pip-VFv5Ni-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:23 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:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:23 INFO - Running setup.py (path:/tmp/pip-hb0jPf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:23 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:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:23 INFO - Running setup.py (path:/tmp/pip-q5NRGW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:24 INFO - Running setup.py (path:/tmp/pip-Cyc1MH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:24 INFO - Running setup.py (path:/tmp/pip-JfggtR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:24 INFO - Running setup.py (path:/tmp/pip-7tmg3e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:24 INFO - Running setup.py (path:/tmp/pip-fd8aPX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:24 INFO - Running setup.py (path:/tmp/pip-lnoqix-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:24 INFO - Running setup.py (path:/tmp/pip-5FDSZS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:24 INFO - Running setup.py (path:/tmp/pip-dbKCcr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:24 INFO - Running setup.py (path:/tmp/pip-Dcg9U1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:24 INFO - Running setup.py (path:/tmp/pip-goYwtV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:24 INFO - Running setup.py (path:/tmp/pip-M2NeCZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:24 INFO - Running setup.py (path:/tmp/pip-9_zvso-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:24 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:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:24 INFO - Running setup.py (path:/tmp/pip-pqGQwq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:24 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:24 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:24 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:24 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:24 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:24 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:24 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:24 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:24 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:24 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:24 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:24 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:24 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:26 INFO - Downloading blessings-1.5.1.tar.gz 16:00:26 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:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:26 INFO - Installing collected packages: blessings 16:00:26 INFO - Running setup.py install for blessings 16:00:26 INFO - Successfully installed blessings 16:00:26 INFO - Cleaning up... 16:00:26 INFO - Return code: 0 16:00:26 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:00:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:00:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:00:27 INFO - Reading from file tmpfile_stdout 16:00:27 INFO - Current package versions: 16:00:27 INFO - argparse == 1.2.1 16:00:27 INFO - blessings == 1.5.1 16:00:27 INFO - blobuploader == 1.2.4 16:00:27 INFO - docopt == 0.6.1 16:00:27 INFO - manifestparser == 1.1 16:00:27 INFO - mozInstall == 1.12 16:00:27 INFO - mozcrash == 0.16 16:00:27 INFO - mozdebug == 0.1 16:00:27 INFO - mozdevice == 0.47 16:00:27 INFO - mozfile == 1.2 16:00:27 INFO - mozhttpd == 0.7 16:00:27 INFO - mozinfo == 0.9 16:00:27 INFO - mozleak == 0.1 16:00:27 INFO - mozlog == 3.0 16:00:27 INFO - moznetwork == 0.27 16:00:27 INFO - mozprocess == 0.22 16:00:27 INFO - mozprofile == 0.27 16:00:27 INFO - mozrunner == 6.11 16:00:27 INFO - mozscreenshot == 0.1 16:00:27 INFO - mozsystemmonitor == 0.0 16:00:27 INFO - moztest == 0.7 16:00:27 INFO - mozversion == 1.4 16:00:27 INFO - psutil == 3.1.1 16:00:27 INFO - requests == 1.2.3 16:00:27 INFO - wsgiref == 0.1.2 16:00:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:27 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:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:27 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:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:27 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 0x7f70aa3e4c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14dc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', '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:27 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:27 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:27 INFO - Using env: {'DISPLAY': ':0', 16:00:27 INFO - 'HOME': '/home/cltbld', 16:00:27 INFO - 'LANG': 'en_US.UTF-8', 16:00:27 INFO - 'LANGUAGE': 'en_US:en', 16:00:27 INFO - 'LOGNAME': 'cltbld', 16:00:27 INFO - 'MAIL': '/var/mail/cltbld', 16:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:27 INFO - 'MOZ_NO_REMOTE': '1', 16:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:27 INFO - 'NO_EM_RESTART': '1', 16:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:27 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:27 INFO - 'SHELL': '/bin/bash', 16:00:27 INFO - 'SHLVL': '1', 16:00:27 INFO - 'TERM': 'linux', 16:00:27 INFO - 'TMOUT': '86400', 16:00:27 INFO - 'USER': 'cltbld', 16:00:27 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:27 INFO - '_': '/tools/buildbot/bin/python'} 16:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:27 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:27 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:27 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:27 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:27 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:27 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:27 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:27 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:27 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:27 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:27 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:27 INFO - Cleaning up... 16:00:27 INFO - Return code: 0 16:00:27 INFO - Running post-action listener: _resource_record_post_action 16:00:27 INFO - Running post-action listener: _start_resource_monitoring 16:00:27 INFO - Starting resource monitoring. 16:00:27 INFO - ##### 16:00:27 INFO - ##### Running install step. 16:00:27 INFO - ##### 16:00:27 INFO - Running pre-action listener: _resource_record_pre_action 16:00:27 INFO - Running main action method: install 16:00:27 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:00:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:00:27 INFO - Reading from file tmpfile_stdout 16:00:27 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:27 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:00:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:00:27 INFO - Reading from file tmpfile_stdout 16:00:27 INFO - Output received: 16:00:27 INFO - Usage: mozinstall [options] installer 16:00:27 INFO - Options: 16:00:27 INFO - -h, --help show this help message and exit 16:00:27 INFO - -d DEST, --destination=DEST 16:00:27 INFO - Directory to install application into. [default: 16:00:27 INFO - "/builds/slave/test-pgo"] 16:00:27 INFO - --app=APP Application being installed. [default: firefox] 16:00:27 INFO - mkdir: /builds/slave/test-pgo/build/application 16:00:27 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:27 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:42 INFO - Reading from file tmpfile_stdout 16:00:42 INFO - Output received: 16:00:42 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:00:42 INFO - Running post-action listener: _resource_record_post_action 16:00:42 INFO - ##### 16:00:42 INFO - ##### Running run-tests step. 16:00:42 INFO - ##### 16:00:42 INFO - Running pre-action listener: _resource_record_pre_action 16:00:42 INFO - Running main action method: run_tests 16:00:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:00:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:00:42 INFO - Python 2.7.3 16:00:42 INFO - Return code: 0 16:00:42 INFO - grabbing minidump binary from tooltool 16:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:42 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 0x1512a90>, '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 0x1564f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15652e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:00:42 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:42 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:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:42 INFO - Return code: 0 16:00:42 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:00:42 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:00:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:00:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:00:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:00:42 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-e10s', '--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-064', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:00:42 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-e10s --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-064 --webServer localhost 16:00:42 INFO - Using env: {'DISPLAY': ':0', 16:00:42 INFO - 'HOME': '/home/cltbld', 16:00:42 INFO - 'LANG': 'en_US.UTF-8', 16:00:42 INFO - 'LANGUAGE': 'en_US:en', 16:00:42 INFO - 'LOGNAME': 'cltbld', 16:00:42 INFO - 'MAIL': '/var/mail/cltbld', 16:00:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:00:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:42 INFO - 'MOZ_NO_REMOTE': '1', 16:00:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:42 INFO - 'NO_EM_RESTART': '1', 16:00:42 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:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:42 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:42 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:00:42 INFO - 'SHELL': '/bin/bash', 16:00:42 INFO - 'SHLVL': '1', 16:00:42 INFO - 'TERM': 'linux', 16:00:42 INFO - 'TMOUT': '86400', 16:00:42 INFO - 'USER': 'cltbld', 16:00:42 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321', 16:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:42 INFO - '_': '/tools/buildbot/bin/python'} 16:00:42 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-e10s', '--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-064', '--webServer', 'localhost'] with output_timeout 3600 16:00:42 INFO - mozversion INFO | application_buildid: 20151120143006 16:00:42 INFO - mozversion INFO | application_changeset: b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 16:00:42 INFO - mozversion INFO | application_display_name: Nightly 16:00:42 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:42 INFO - mozversion INFO | application_name: Firefox 16:00:42 INFO - mozversion INFO | application_remotingname: firefox 16:00:42 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 16:00:42 INFO - mozversion INFO | application_vendor: Mozilla 16:00:42 INFO - mozversion INFO | application_version: 45.0a1 16:00:42 INFO - mozversion INFO | platform_buildid: 20151120143006 16:00:42 INFO - mozversion INFO | platform_changeset: b0bdf1bc3b61fbe002d820ba0bd88ebedae92b73 16:00:42 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 16:00:42 INFO - mozversion INFO | platform_version: 45.0a1 16:00:42 INFO - 2015-11-20 16:00:42,406 DEBUG : using testdate: 1448064042 16:00:42 INFO - 2015-11-20 16:00:42,406 DEBUG : actual date: 1448064042 16:00:42 INFO - 2015-11-20 16:00:42,415 INFO : starting webserver on 'localhost:57211' 16:00:42 INFO - 2015-11-20 16:00:42,416 INFO : Starting test suite talos-linux64-ix-064.e 16:00:42 INFO - 2015-11-20 16:00:42,416 INFO : Starting test dromaeo_css 16:00:42 INFO - 2015-11-20 16:00:42,417 DEBUG : operating with platform_type : linux_ 16:00:42 INFO - 2015-11-20 16:00:42,417 INFO : Initialising browser for dromaeo_css test... 16:00:42 INFO - 2015-11-20 16:00:42,434 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZTxeU4/profile http://localhost:57211/getInfo.html 16:00:47 INFO - 2015-11-20 16:00:47,461 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:00:47 INFO - 2015-11-20 16:00:47,462 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:00:47 INFO - 2015-11-20 16:00:47,462 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:00:47 INFO - 2015-11-20 16:00:47,462 DEBUG : BROWSER_OUTPUT: __metrics 16:00:48 INFO - 2015-11-20 16:00:48,453 INFO : Browser initialized. 16:00:48 INFO - 2015-11-20 16:00:48,454 INFO : Running cycle 1/1 for dromaeo_css test... 16:00:48 INFO - 2015-11-20 16:00:48,454 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZTxeU4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:01:00 INFO - 2015-11-20 16:01:00,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 141778944 16:01:00 INFO - 2015-11-20 16:01:00,277 DEBUG : BROWSER_OUTPUT: 16:01:49 INFO - 2015-11-20 16:01:49,897 DEBUG : BROWSER_OUTPUT: 1448064109895 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:59 INFO - 2015-11-20 16:03:59,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57211/tests/dromaeo/cssquery-ext.html) 16:04:00 INFO - 2015-11-20 16:04:00,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 145027072 16:04:00 INFO - 2015-11-20 16:04:00,449 DEBUG : BROWSER_OUTPUT: 16:07:02 INFO - 2015-11-20 16:07:02,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/dromaeo/cssquery-ext.html (next: http://localhost:57211/tests/dromaeo/cssquery-jquery.html) 16:07:03 INFO - 2015-11-20 16:07:03,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 142352384 16:07:03 INFO - 2015-11-20 16:07:03,018 DEBUG : BROWSER_OUTPUT: 16:10:04 INFO - 2015-11-20 16:10:04,666 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57211/tests/dromaeo/cssquery-mootools.html) 16:10:05 INFO - 2015-11-20 16:10:05,268 DEBUG : BROWSER_OUTPUT: RSS: Main: 142827520 16:10:05 INFO - 2015-11-20 16:10:05,268 DEBUG : BROWSER_OUTPUT: 16:13:06 INFO - 2015-11-20 16:13:06,883 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57211/tests/dromaeo/cssquery-prototype.html) 16:13:07 INFO - 2015-11-20 16:13:07,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 141185024 16:13:07 INFO - 2015-11-20 16:13:07,469 DEBUG : BROWSER_OUTPUT: 16:16:09 INFO - 2015-11-20 16:16:09,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57211/tests/dromaeo/cssquery-yui.html) 16:16:10 INFO - 2015-11-20 16:16:10,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 141893632 16:16:10 INFO - 2015-11-20 16:16:10,387 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,666 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/dromaeo/cssquery-yui.html (next: http://localhost:57211/tests/dromaeo/cssquery-dojo.html) 16:19:12 INFO - 2015-11-20 16:19:12,916 DEBUG : BROWSER_OUTPUT: RSS: Main: 144961536 16:19:12 INFO - 2015-11-20 16:19:12,916 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,916 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:19:12 INFO - 2015-11-20 16:19:12,916 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:19:12 INFO - 2015-11-20 16:19:12,916 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:19:12 INFO - 2015-11-20 16:19:12,916 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:19:12 INFO - 2015-11-20 16:19:12,920 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3201.295714942713;3246.853891574879;3255.6093268807745;3273.9181520461984;3292.242784159643;5215.687058776476;5320.909213611215;5332.626716129873;5356.8125115620915;5392.649477783951;5298.20526920962;5360.597955153369;5371.382291036532;5411.648242864211;5416;4915.090708218973;4989.077020751154;5035.244713292998;5050.671706339094;5075.187969924813;4931.876703082416;4999.0501804657;5016.548510634042;5023.296738456619;5029.949700503003;4822.373091498118;4926.852194434173;4935.136351138536;5001.09980203565;5007.2489126630935;4868.391451068616;4889.975550122263;4926.187179115442;4941.975290123562;4958.132326842788;4939.506049395068;4945.505449455061;4954;4970.925436118461;4997.725125118117;5048.924266136011;5182.222666600002;5189.065968125736;5248.475152484759;5274.182501712241;5731.541476681856;5761.9135712964335;5784.566157538202;5785.71071446426;5828.825135245949;5895.204147440084;5910.143029260733;5916;5922.378150294243;5928.051511758141;5254.395744489432;5380.596455265871;5481.917771233435;5483.698396588186;5516.227728118067;5850.23946886902;5861.472467477862;5911.970440147772;5914.2311499504785;5927.585069045212;5744.569157313218;5746.9712651436475;5758.683272420057;5776.393478684761;5778;5723.484886360247;5774.711264436762;5784.479396854219;5797.420257974169;5809.448102430347;5733.254676891977;5740.339860915965;5753.741081651336;5757.050086735641;5773.88452230953;5612.074007788669;5694.971525142348;5738.913916291259;5741.770329186786;5771.509421699118;4525.366448697185;4541.72749635023;4546.568076032771;4578.542145785462;4584.060267645124;4011.919761604752;4013.4381186633896;4045.7167998239843;4056.675465962716;4076.9388459173138;5387.3804512481565;5496.258005169251;5510.201020852034;5570.610057296031;5612.214289999403;5866.911996320058;5867.970660146757;5890.528757699374;5907.527397808165;5929.0513517836935;5590.91613625796;5604.775808967677;5608.69152196625;5617.550595952314;5618.4662457066515;4967.552920237196;4987.775550100254;5085.415177254589;5099.490050994945;5160.174372100446;5875.41245875409;5938.6436813791315;5944.781319829424;5974.910376344358;5976.073708575178;5756.625819321731;5758.683272419889;5761.423857614205;5771.307443106854;5817.418258174149;5840.065589505659;5843.211166492639;5957.553183511341;5963.940360596339;6009.969950150221;5743.195952566561;5753;5759.94240057594;5762.337331206965;5774.422557744192;5599.524040456607;5853.326867410302;5862.970685146717;5868.501177399798;5872.970635146797;5642.379338272869;5664.745086471036;5696.458836410535;5714.971425142848;5800.622959507618;5627.380988091225;5631.521320687623;5631.88736225265;5637.774489020393;5662.405447428126;5525.640833345819;5565.6104072714525;5579.19101730255;5590.440955904377;5590.99362114807;5509.925564514881;5638.295213098363;5646.943530564642;5710.571707122065;5754.021816291324;5568.972155139199;5574.275344205227;5616.522595579258;5620.297462817148;5621.409751976147 16:19:12 INFO - 2015-11-20 16:19:12,920 DEBUG : BROWSER_OUTPUT: |1;ext.html;531.232223700479;572.1189368372703;576.8673205162816;585.9384764599716;595.0924839619581;37467.250654986885;37624.43563346549;37692.81153594228;37812;37891.24217515648;42611.573884261146;42917.14165716692;43204.567954320446;43245.35131973023;43267;8829.205371516578;8831.205191532792;8913.420627659214;8950.731478055668;8987.910120898805;8599.527026013568;8789.033206347296;8810.163034511712;8811;8823.676448532708;8740.4755714657;8799.648014079428;8809.03100658927;8828.205461508463;8834.337424693174;8703.782405439882;8843.646254149826;8886.822263554724;8888.422252553562;8916.777080572972;8682.088380719997;8846.46921184731;8875.201231889096;8882.86675699865;8892.199702026848;39755.60244397548;39833.40249896254;39854;40001.19997600061;40214.396784048266;3281.503575806615;3300.9834950825334;3725.5156829612247;3738.12154143776;3786.829592668309;30112.54831177534;31036.844815775778;31213;31311.37377252442;31370.843145784354;3767.56672982605;3802.7692674969176;3957.604239576019;3985.5217373915025;4101.2207680540605;263908;270379;270727;270759;271654;63459;64198.679006605606;64280;64365.35634643687;64619.67690161519;61554.38445615576;63843;69243;69433.65283173551;69915.3008469919;15838.76241856373;16087;16097.517074487783;16185.271662775203;16270;11032.28290161137;11109.166812489095;11111.611093611686;11123.443827808576;11127.443627818577;21232.787672123082;21748.238811641833;21788.237411690516;21887.890560547094;21898.562028759654;8650.394472386872;8925.983307511646;8956.373053886296;8975.955120224357;8984.371094023356;1011.8886922438526;1045.3362115056993;1073.8765042020111;1079.8434227037035;1126.9492872820742;28963.275918101794;29133.708662913523;29137.708622913924;29141.27146821304;29200.853995729885;1023.5649848814224;1066.6053560182781;1081.4106312059946;1091.3615534912008;1145.5761368293784;15201.239938003055;15241.466548670744;15338;15523.767143492856;15531.84468155304;325820;327151;328344;329222;329417;52990;53364.19953700698;53723.19415208775;53801.461985380425;53810;1353.1610401551102;1372.4304413668415;1372.6225288045866;1378.800073989266;1383.266868559665;27947.44105117927;28620;28880.85559572273;29247;29290.7070929288;50031;50838.74580627073;51259.2311115341;51283.74358128185;51324.74337628288;301613;301770;303142;305192;305400;43319;46151.53848461472;46487.76756116333;47406.28890566575;47922.5207747918;44607;44611.33083003823;44635.77682111698;45152;45533.4466553342;37316.813415932746;37428.251434971695;38335.42496862494;38380.80809595934;38892.80553597214;37141.257174856895;37419.81290093532;38376.80811595924;38390.616093839824;38481.807590961864;36958;37179.070523236056;41211;42161.156776864904;42230.577694223895;31537.52693709642;37043;38209.426858596584;38724.80637596794;38888.222235555695;25814.741852581235;26356;26387.340316492242;26465.33836654022;26484.337891552866 16:19:12 INFO - 2015-11-20 16:19:12,921 DEBUG : BROWSER_OUTPUT: |2;jquery.html;352.918828669406;356.91969306905946;358.6306104712146;360.49530657080066;363.36774013217;16657.666846663076;17107.23017464214;17698.203580838875;17706.46880593581;18124.63750724985;10741.409222492759;11897.58358457452;17234.741478877826;17264.80498086388;17415;4737.194676904917;4870.805167793284;4884.6811360932525;4885.071836351082;4893.2170852663585;4848.296996935443;4863.14894893394;4902.632302577304;4906.313116163722;4906.730129842857;4770.642201834877;5009.173486374762;5018.698878067309;5038.244263360507;5199.532042116209;5240.9213861792105;5270.789168433258;5308.256844041837;5323.574114070865;5330.28041214435;5082.440931497532;5342.946570534284;5344.225087362311;5345.812896548641;5358.2498450217;12230.14388992771;12407;14889.47886823956;20178;20237.696434553494;1947.5033866363985;2034.7965203479682;2056.4550394145563;2076.979230207709;2140.3578926322043;9053.230475409599;9101.317401194938;9437.528123593862;9530.094641009093;9726.27052971023;1861.8324350808493;1881.0594702648675;1886.8018858019984;1887.1319193171157;1935.0808366026165;61905.38094619041;64656.676716616115;66685.3331466684;67211.99182012274;67602.9999999995;9733.805323893483;9878.802423951482;9980.600775969026;10075.496225188712;10084.546195421224;13111.344432778324;13112.803307950388;13117.803232951514;13119.540816071392;13129.540466083832;7107.147142342952;7126.35862772353;7178.102737157856;7261.636918154071;7270.854582908312;6868.244493105754;6884;6906.930930690629;6954.3045695431065;6956.991236270713;4233.851815186454;4262.7016108872085;4301;4301.666483390159;4385.561443855589;1746.2054765081857;1747.8339557741995;1755.4558086992818;1762.3303144805147;1787.3118849243135;403.2036727463295;417.22812796326554;431.8207943703328;441.3357896365949;969.2778879734502;10149.39103653783;10271.537780799881;10324.845127323097;10435.634752783617;10461.372317660966;439.0319345842474;451.92091384007665;457.32772823948744;863.3007264116086;955.7562821480478;8883.955580222058;9071.909280907106;9256.444613323221;9272.258219342437;9376.484293363794;512383;514070;515440;518748;523398.06407744024;79876.20123798688;82488;83188;85247.14752852391;87833.12166878377;1134.0304040045642;1137.4710759496875;1142.8914253145938;1168.7428765671539;1169.1348402182493;10214;15307.540773776804;15558.533244002698;15655.765163522328;15841.128737919526;35912.64087359093;35949.28101437904;36469.63530364769;37246.44130337993;37594.06014849541;46208.76895615501;47608.76195619;49739.253911192085;50938.490615093375;54387.72806135944;15116.697666046397;15151.848481515044;15229;15248.466303679317;15340.38638454494;18437.354692585694;18512.352067677566;18845.24619015224;19083.90458047701;19233.65364424477;9446;9906.108450239513;15522.84477155214;15819.446319378767;15820.60448488797;9931.851022234529;10053;10204.38773673582;10282.485875706036;10333.689989300334;9501.477418742035;9954.253430992849;9989.850152247576;10043.548040338057;10243.129334006693;14831.258437078319;14895;14979.700405991602;15106.093634381972;15282;15568.688626227638;15747.763783543027;15997.600059998593;16090.436834710728;16324.428644997368 16:19:12 INFO - 2015-11-20 16:19:12,922 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1492.1345619540668;1668.7580300856375;1719.7592337072813;1735.887167334124;1737.774089368382;7079.398251148652;7459;7516.022917020787;7573.659185336659;7697.846043079149;7610.972518709972;7621.314081732643;7687.923120768791;7691.1924247954;7701.576413297266;2302.7351854536773;2344.050659482912;2351.2123438648073;2352.9058837646558;2353.9646905296436;2311.2603966730653;2342.683737695415;2349.706286714161;2356.5051339218785;2357.917472888449;2214.236088549456;2241.753407125223;2251.4821591034074;2251.7297924249115;2259.2770313499686;2315.6526521021897;2338.298510446859;2339.3332900123382;2340.3447034830274;2342.2387723989686;2251.5609456156058;2313.063209400196;2322.790948814598;2329.0450915124716;2332.661764044221;7406.888896666501;7439.181690014094;7455.2172021938;7458.776236712907;7489.063867016623;4542.068875880436;4547.47704014036;4608.446986361658;4620.8151673933;4625.051864367797;2962.1113665900143;2987.208389776711;3015.638123425181;3017.9396412071637;3024.395120975814;981.4160574458186;982.5332966840693;996.043797953965;1001.4792307999872;1028.3469996552244;10118.595256189743;10132.594696212143;10180.694579162562;10198.643047493302;10215.182785377152;4077.89805254871;4120.505539335299;4138.772367519815;4160.45914031174;4161.791910404468;8275.917240827635;8295.377846661524;8333.66665333392;8336.458130221516;8371.95814020926;4478;4494.775261236925;4506.301523263899;4535.138323718549;4565.406497155349;4151.920500669848;4170.72890262132;4193.853215137456;4194.559571244975;4211.010412553048;3468.3930312195266;3497.0557949353533;3525.1892064825233;3539.3983022886173;3542.822858857047;1108.4734750993375;1119.1326721790515;1120.1822669451346;1122.2144498850837;1123.3821398230941;1064.3188359449957;1066.258950029722;1080.443571560647;1221.2550344289975;1331.7536255792709;6638;6810.897836532456;6819.5908245505425;6852.109225800614;6854.0404343391965;1136.8635763708241;1144.8053830848776;1149.804533229353;1153.325304696745;1160.4255893332854;5380.811671591476;5399.676019438846;5405.054115529767;5436.157395603688;5440.972795135999;8729;8749.56252187388;8914.242289405473;9010.864837027319;9055.411398259092;8958.86561701575;8965.686200982935;8971.641134354553;8974.282057435521;8995.55022248885;6674.933250667528;6726.7981960541265;6729.6971636276485;6917.996890450857;6927.618980955995;6441.710123044568;6461.644609546332;6469.514786390945;6493.123428337114;6494.935050649433;7497.88753168692;7651.081870175614;7716.729914452967;7837.37301015906;7936.761897143095;7778.0666320041955;7903.249191326815;7906.723264685708;7919.762407127795;7984.760457186294;6643.534952553275;6685.799426017227;6706.362895524917;6737.393634572912;6760.256371799196;6967.303269673195;7040.119985001875;7040.929590704028;7054.224035356209;7064.152301723826;6503.057056726842;6579.80260592183;6592.143021407186;6595.703193356215;6870.416014638612;6376.171097757262;6508.088867558451;6508.73965041412;6535.607863528204;6603.174603174603;6443.420092191725;6483.286838447672;6720.361565651256;6726.899096513458;6760.323967603397;6037.245344331958;6058;6087.330393656783;6091.451769340781;6110.327863935053;6069;6084.361142080018;6117.235345581802;6122.877542449037;6125.111858780363 16:19:12 INFO - 2015-11-20 16:19:12,924 DEBUG : BROWSER_OUTPUT: |4;prototype.html;316.40200021958503;333.4964204051882;341.89230392426384;343.50878244110925;346.11049602521496;6387.840303992397;6391.680415979194;6407.391297826704;6417.614943103417;6465.159529261189;6230.750769969196;6307.400796924297;6325.399087086731;6342.746290148389;6342.873142537146;4833.806647734086;4835.516448355172;4839.443464001641;4859.854204373874;4873.147199240118;4851.757412129397;4855.077535268305;4856.150173719585;4860.4896485869;4860.6111511079025;4682.344471773954;4765.451973023095;4784.5693887550115;4799.856004319876;4801.663883528155;4772.284157376386;4783.497732738065;4798.592119669832;4801.831935882262;4819.108464933998;4164.708470407073;4175.770332631704;4177.060161463677;4178.707490475668;4180.352045432963;5959.165716799652;5971.223740913653;5996.700164991733;6006.069059295773;6009.128676341906;5501.367342755594;5588.133839254922;5634.267545219136;5646.632968857011;5659.547236221092;6717.193936727575;6728.158980127484;6728.966355168192;6733.158355205599;6754.3921047105505;4616.146012987608;4627.097715945392;4652.371929789503;4660.37084993525;4669.509701481364;6785.898211526831;6923.861522769566;6951.339622735832;7033.50765446419;7041.753538626123;4601.562851529099;4623.86128416148;4625.121226966866;4628.606568441653;4631.027484228282;4673.228917228687;4674.322223277606;4676.836310729108;4680.08738296029;4686.789094490756;5515.7242137892945;5521.061419558685;5522.91715624266;5527.53015993637;5530.142827861688;6596.538242323088;6721.1598550181225;6754.223264324665;6782.118324617867;6812.659367031728;1673.238676402234;1697.8726595505386;1699.2947926610566;1702.182952182959;1704.880658353928;1140.674907651332;1152.072581571826;1157.7973854575584;1165.0504838556549;1165.5454372794618;6322.304546499881;6447.580907241015;6448.419642232222;6452.0967064610995;6463.64449955257;6789.932100679029;6792.76225332111;6852;6857.622830744257;6863.039174515572;6338.714757835908;6373.5857169283845;6432.099506069154;6443.48452123829;6444.291127975919;3816.713746469026;3845.7692538447222;3857.5756666766633;3859.3053250415196;3861.555921069113;7378.188399276075;7407.2222416646555;7407.370373518203;7420.406367490587;7423.665935032936;7056.400205982446;7232.168300645388;7291.744788932362;7305.707771689179;7315.378192853667;7296.927030729731;7308.574827908506;7381.37258333037;7401.666924988388;7413.703451861973;6999.860002800017;7038.8944165838575;7066.4346852250665;7079.504434689522;7095.467839911924;6872.175338959357;6911.17065952089;6986.6855991481525;6991.126109236346;6994.930050699428;7309.853802924018;7330.633468326456;7336.046313979148;7336.193018768038;7343.045404097433;4666.883327916829;4712.198926182626;4724.574788268935;4729.69256998294;4750.334953106499;6848.315168483311;6867.141607299088;7011.193712723102;7030.578165310098;7035.437165026683;6766.255711871789;6821.590704557742;6830.931690683228;6849.794506164823;6863.313668633096;6811.250762416031;6815;6827.6244806536115;6828.556143850634;6832.555883867532;6571;6629.535932484872;6660.167479065117;6676.399124078662;6707.966460167668;6805.727770889109;6823.692933817891;6846.623435710884;6850.451963842781;6878.3465570770695;6584.9670751647855;6591.967040164768;6614.908540090831;6616.305287944697;6618.470522358296 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: |5;yui.html;769.8575763483759;819.9508029518229;821.8685010398334;827.8302947895681;848.4442690038026;11748.823767643482;11931.821022684666;11963;11970.34163121028;11990;11713.414329283523;11736.413179341042;11789.351585662786;11795.233309834875;12421.627351179479;810.5217921426372;816.346922462031;816.7182322098867;817.8159914019358;817.9591020448984;798.3134504326304;799.6481548118838;800.0319613267924;804.0833449867156;804.5896592737687;845.18439705684;846.5597889097666;847.4576271186446;847.8346722389136;851.8126012277289;846.8645016797313;847.7499137754346;848.3722045686147;848.6647774129216;851.2721623012297;624.9125122482856;625.527726566444;628.676231740654;628.7139351595059;629.9055141728732;12718.364081795966;12746.235225886476;12818.102732808708;12838.486460541568;12878;11374.431278436045;11625.6512304631;11679.182457227997;11781.41092945358;12094.697632559168;9961.601535938555;9998.05018523239;10034.09693127622;10113.797724045478;10168.644097456554;3264.7877887937207;3269.3461307738307;3280.6391296957318;3316.9385796545116;3323.0861513083855;48399;48689;48839.26741098886;48935.26597101046;49056.26415603769;9417.62329506812;9601.32790704644;9706.272029597809;9709.36889102206;9808.656697015713;17836.91081544584;17924.91037544804;17967.1016449177;17993.100344982697;17999.64000719978;7489.176190619028;7547;7651.387888968869;7793.337566306814;7946.205379462123;4349.739015659071;4413.823447062082;4418.955810441918;4422.425084738963;4431.955680443219;3073.1241596145437;3111.7510599152015;3121.500559910449;3126.155937896755;3127.03062050765;528.7858417340983;540.9053415652245;541.2098336428858;542.2950164785763;548.8463230295523;570.4067769519736;674.8380388706676;700.1458220970408;700.3276854220006;714.6569646569676;8911.197992180734;9326.95336523313;9368.95315523418;9527.714168574954;9715.22278217755;606.2452246952572;685.5578152091873;703.7339469972554;714.2536049827942;715.2418436457372;4000.8599699010397;4146.232946904832;4196.853110141131;4208.747475151501;4276.935845962313;61403.6929815357;63230.36769632337;63248.367516324244;63349.36650633527;63439.68280158662;53702.731486342316;53977;54895.45104548983;55623.72188139033;56223;49736;50074;50143.24785128226;50831.74584127056;51588.22617660738;8135.77559366931;8507.106753790762;8511.574421278787;8965.551722413722;9071.863922041042;32239.838800805846;33319.83340083284;33375.33249334951;35564;37145.442818358286;42503.149937001705;42534.36198456964;45098.54901450944;45617.31574026326;46359.768201158775;11362.068448363963;11384.943075284571;11451;11459.598914037968;11661.883381166079;12093.395330233277;12121.454534546143;12173.69565760863;12586.370681465707;12637.494500220257;11321.31670000947;11533.88466115328;11656.358900260568;12163.2702037878;12207.145499814927;11816.881831181578;11933.46299416511;12097.274163550215;12178.269303841798;12501.937490312854;11476.483558240066;11742.647720568397;11758.3532905691;11985.820212696643;11994.940025299818;9232.676856309998;9702.6118955241;9877.209823213982;9889.554970026222;9914.752131196778;9057.637694492147;9289.767755806159;9480.525973701426;9855.556499957376;10211.7447063824 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,297 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: __startTimestamp1448065152915__endTimestamp 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:19:12 INFO - 2015-11-20 16:19:12,925 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5394.68 Median:5612.21 stddev:581.50 (10.4%) stddev-sans-first:557.25 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: Values: 3201.3 3246.9 3255.6 3273.9 3292.2 5215.7 5320.9 5332.6 5356.8 5392.6 5298.2 5360.6 5371.4 5411.6 5416.0 4915.1 4989.1 5035.2 5050.7 5075.2 4931.9 4999.1 5016.5 5023.3 5029.9 4822.4 4926.9 4935.1 5001.1 5007.2 4868.4 4890.0 4926.2 4942.0 4958.1 4939.5 4945.5 4954.0 4970.9 4997.7 5048.9 5182.2 5189.1 5248.5 5274.2 5731.5 5761.9 5784.6 5785.7 5828.8 5895.2 5910.1 5916.0 5922.4 5928.1 5254.4 5380.6 5481.9 5483.7 5516.2 5850.2 5861.5 5912.0 5914.2 5927.6 5744.6 5747.0 5758.7 5776.4 5778.0 5723.5 5774.7 5784.5 5797.4 5809.4 5733.3 5740.3 5753.7 5757.1 5773.9 5612.1 5695.0 5738.9 5741.8 5771.5 4525.4 4541.7 4546.6 4578.5 4584.1 4011.9 4013.4 4045.7 4056.7 4076.9 5387.4 5496.3 5510.2 5570.6 5612.2 5866.9 5868.0 5890.5 5907.5 5929.1 5590.9 5604.8 5608.7 5617.6 5618.5 4967.6 4987.8 5085.4 5099.5 5160.2 5875.4 5938.6 5944.8 5974.9 5976.1 5756.6 5758.7 5761.4 5771.3 5817.4 5840.1 5843.2 5957.6 5963.9 6010.0 5743.2 5753.0 5759.9 5762.3 5774.4 5599.5 5853.3 5863.0 5868.5 5873.0 5642.4 5664.7 5696.5 5715.0 5800.6 5627.4 5631.5 5631.9 5637.8 5662.4 5525.6 5565.6 5579.2 5590.4 5591.0 5509.9 5638.3 5646.9 5710.6 5754.0 5569.0 5574.3 5616.5 5620.3 5621.4 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48477.98 Median:38272.43 stddev:78665.80 (205.5%) stddev-sans-first:78804.10 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: Values: 531.2 572.1 576.9 585.9 595.1 37467.3 37624.4 37692.8 37812.0 37891.2 42611.6 42917.1 43204.6 43245.4 43267.0 8829.2 8831.2 8913.4 8950.7 8987.9 8599.5 8789.0 8810.2 8811.0 8823.7 8740.5 8799.6 8809.0 8828.2 8834.3 8703.8 8843.6 8886.8 8888.4 8916.8 8682.1 8846.5 8875.2 8882.9 8892.2 39755.6 39833.4 39854.0 40001.2 40214.4 3281.5 3301.0 3725.5 3738.1 3786.8 30112.5 31036.8 31213.0 31311.4 31370.8 3767.6 3802.8 3957.6 3985.5 4101.2 263908.0 270379.0 270727.0 270759.0 271654.0 63459.0 64198.7 64280.0 64365.4 64619.7 61554.4 63843.0 69243.0 69433.7 69915.3 15838.8 16087.0 16097.5 16185.3 16270.0 11032.3 11109.2 11111.6 11123.4 11127.4 21232.8 21748.2 21788.2 21887.9 21898.6 8650.4 8926.0 8956.4 8976.0 8984.4 1011.9 1045.3 1073.9 1079.8 1126.9 28963.3 29133.7 29137.7 29141.3 29200.9 1023.6 1066.6 1081.4 1091.4 1145.6 15201.2 15241.5 15338.0 15523.8 15531.8 325820.0 327151.0 328344.0 329222.0 329417.0 52990.0 53364.2 53723.2 53801.5 53810.0 1353.2 1372.4 1372.6 1378.8 1383.3 27947.4 28620.0 28880.9 29247.0 29290.7 50031.0 50838.7 51259.2 51283.7 51324.7 301613.0 301770.0 303142.0 305192.0 305400.0 43319.0 46151.5 46487.8 47406.3 47922.5 44607.0 44611.3 44635.8 45152.0 45533.4 37316.8 37428.3 38335.4 38380.8 38892.8 37141.3 37419.8 38376.8 38390.6 38481.8 36958.0 37179.1 41211.0 42161.2 42230.6 31537.5 37043.0 38209.4 38724.8 38888.2 25814.7 26356.0 26387.3 26465.3 26484.3 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28274.95 Median:18998.63 stddev:84688.53 (445.8%) stddev-sans-first:84900.15 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: Values: 352.9 356.9 358.6 360.5 363.4 16657.7 17107.2 17698.2 17706.5 18124.6 10741.4 11897.6 17234.7 17264.8 17415.0 4737.2 4870.8 4884.7 4885.1 4893.2 4848.3 4863.1 4902.6 4906.3 4906.7 4770.6 5009.2 5018.7 5038.2 5199.5 5240.9 5270.8 5308.3 5323.6 5330.3 5082.4 5342.9 5344.2 5345.8 5358.2 12230.1 12407.0 14889.5 20178.0 20237.7 1947.5 2034.8 2056.5 2077.0 2140.4 9053.2 9101.3 9437.5 9530.1 9726.3 1861.8 1881.1 1886.8 1887.1 1935.1 61905.4 64656.7 66685.3 67212.0 67603.0 9733.8 9878.8 9980.6 10075.5 10084.5 13111.3 13112.8 13117.8 13119.5 13129.5 7107.1 7126.4 7178.1 7261.6 7270.9 6868.2 6884.0 6906.9 6954.3 6957.0 4233.9 4262.7 4301.0 4301.7 4385.6 1746.2 1747.8 1755.5 1762.3 1787.3 403.2 417.2 431.8 441.3 969.3 10149.4 10271.5 10324.8 10435.6 10461.4 439.0 451.9 457.3 863.3 955.8 8884.0 9071.9 9256.4 9272.3 9376.5 512383.0 514070.0 515440.0 518748.0 523398.1 79876.2 82488.0 83188.0 85247.1 87833.1 1134.0 1137.5 1142.9 1168.7 1169.1 10214.0 15307.5 15558.5 15655.8 15841.1 35912.6 35949.3 36469.6 37246.4 37594.1 46208.8 47608.8 49739.3 50938.5 54387.7 15116.7 15151.8 15229.0 15248.5 15340.4 18437.4 18512.4 18845.2 19083.9 19233.7 9446.0 9906.1 15522.8 15819.4 15820.6 9931.9 10053.0 10204.4 10282.5 10333.7 9501.5 9954.3 9989.9 10043.5 10243.1 14831.3 14895.0 14979.7 15106.1 15282.0 15568.7 15747.8 15997.6 16090.4 16324.4 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,926 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5203.27 Median:6085.85 stddev:2610.97 (42.9%) stddev-sans-first:2603.39 16:19:12 INFO - 2015-11-20 16:19:12,928 DEBUG : BROWSER_OUTPUT: Values: 1492.1 1668.8 1719.8 1735.9 1737.8 7079.4 7459.0 7516.0 7573.7 7697.8 7611.0 7621.3 7687.9 7691.2 7701.6 2302.7 2344.1 2351.2 2352.9 2354.0 2311.3 2342.7 2349.7 2356.5 2357.9 2214.2 2241.8 2251.5 2251.7 2259.3 2315.7 2338.3 2339.3 2340.3 2342.2 2251.6 2313.1 2322.8 2329.0 2332.7 7406.9 7439.2 7455.2 7458.8 7489.1 4542.1 4547.5 4608.4 4620.8 4625.1 2962.1 2987.2 3015.6 3017.9 3024.4 981.4 982.5 996.0 1001.5 1028.3 10118.6 10132.6 10180.7 10198.6 10215.2 4077.9 4120.5 4138.8 4160.5 4161.8 8275.9 8295.4 8333.7 8336.5 8372.0 4478.0 4494.8 4506.3 4535.1 4565.4 4151.9 4170.7 4193.9 4194.6 4211.0 3468.4 3497.1 3525.2 3539.4 3542.8 1108.5 1119.1 1120.2 1122.2 1123.4 1064.3 1066.3 1080.4 1221.3 1331.8 6638.0 6810.9 6819.6 6852.1 6854.0 1136.9 1144.8 1149.8 1153.3 1160.4 5380.8 5399.7 5405.1 5436.2 5441.0 8729.0 8749.6 8914.2 9010.9 9055.4 8958.9 8965.7 8971.6 8974.3 8995.6 6674.9 6726.8 6729.7 6918.0 6927.6 6441.7 6461.6 6469.5 6493.1 6494.9 7497.9 7651.1 7716.7 7837.4 7936.8 7778.1 7903.2 7906.7 7919.8 7984.8 6643.5 6685.8 6706.4 6737.4 6760.3 6967.3 7040.1 7040.9 7054.2 7064.2 6503.1 6579.8 6592.1 6595.7 6870.4 6376.2 6508.1 6508.7 6535.6 6603.2 6443.4 6483.3 6720.4 6726.9 6760.3 6037.2 6058.0 6087.3 6091.5 6110.3 6069.0 6084.4 6117.2 6122.9 6125.1 16:19:12 INFO - 2015-11-20 16:19:12,928 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,928 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5641.93 Median:6412.50 stddev:1727.20 (26.9%) stddev-sans-first:1685.16 16:19:12 INFO - 2015-11-20 16:19:12,928 DEBUG : BROWSER_OUTPUT: Values: 316.4 333.5 341.9 343.5 346.1 6387.8 6391.7 6407.4 6417.6 6465.2 6230.8 6307.4 6325.4 6342.7 6342.9 4833.8 4835.5 4839.4 4859.9 4873.1 4851.8 4855.1 4856.2 4860.5 4860.6 4682.3 4765.5 4784.6 4799.9 4801.7 4772.3 4783.5 4798.6 4801.8 4819.1 4164.7 4175.8 4177.1 4178.7 4180.4 5959.2 5971.2 5996.7 6006.1 6009.1 5501.4 5588.1 5634.3 5646.6 5659.5 6717.2 6728.2 6729.0 6733.2 6754.4 4616.1 4627.1 4652.4 4660.4 4669.5 6785.9 6923.9 6951.3 7033.5 7041.8 4601.6 4623.9 4625.1 4628.6 4631.0 4673.2 4674.3 4676.8 4680.1 4686.8 5515.7 5521.1 5522.9 5527.5 5530.1 6596.5 6721.2 6754.2 6782.1 6812.7 1673.2 1697.9 1699.3 1702.2 1704.9 1140.7 1152.1 1157.8 1165.1 1165.5 6322.3 6447.6 6448.4 6452.1 6463.6 6789.9 6792.8 6852.0 6857.6 6863.0 6338.7 6373.6 6432.1 6443.5 6444.3 3816.7 3845.8 3857.6 3859.3 3861.6 7378.2 7407.2 7407.4 7420.4 7423.7 7056.4 7232.2 7291.7 7305.7 7315.4 7296.9 7308.6 7381.4 7401.7 7413.7 6999.9 7038.9 7066.4 7079.5 7095.5 6872.2 6911.2 6986.7 6991.1 6994.9 7309.9 7330.6 7336.0 7336.2 7343.0 4666.9 4712.2 4724.6 4729.7 4750.3 6848.3 6867.1 7011.2 7030.6 7035.4 6766.3 6821.6 6830.9 6849.8 6863.3 6811.3 6815.0 6827.6 6828.6 6832.6 6571.0 6629.5 6660.2 6676.4 6708.0 6805.7 6823.7 6846.6 6850.5 6878.3 6585.0 6592.0 6614.9 6616.3 6618.5 16:19:12 INFO - 2015-11-20 16:19:12,928 DEBUG : BROWSER_OUTPUT: 16:19:12 INFO - 2015-11-20 16:19:12,928 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14108.01 Median:50108.62 stddev:16858.00 (33.6%) stddev-sans-first:16875.53 16:19:12 INFO - 2015-11-20 16:19:12,929 DEBUG : BROWSER_OUTPUT: Values: 769.9 820.0 821.9 827.8 848.4 11748.8 11931.8 11963.0 11970.3 11990.0 11713.4 11736.4 11789.4 11795.2 12421.6 810.5 816.3 816.7 817.8 818.0 798.3 799.6 800.0 804.1 804.6 845.2 846.6 847.5 847.8 851.8 846.9 847.7 848.4 848.7 851.3 624.9 625.5 628.7 628.7 629.9 12718.4 12746.2 12818.1 12838.5 12878.0 11374.4 11625.7 11679.2 11781.4 12094.7 9961.6 9998.1 10034.1 10113.8 10168.6 3264.8 3269.3 3280.6 3316.9 3323.1 48399.0 48689.0 48839.3 48935.3 49056.3 9417.6 9601.3 9706.3 9709.4 9808.7 17836.9 17924.9 17967.1 17993.1 17999.6 7489.2 7547.0 7651.4 7793.3 7946.2 4349.7 4413.8 4419.0 4422.4 4432.0 3073.1 3111.8 3121.5 3126.2 3127.0 528.8 540.9 541.2 542.3 548.8 570.4 674.8 700.1 700.3 714.7 8911.2 9327.0 9369.0 9527.7 9715.2 606.2 685.6 703.7 714.3 715.2 4000.9 4146.2 4196.9 4208.7 4276.9 61403.7 63230.4 63248.4 63349.4 63439.7 53702.7 53977.0 54895.5 55623.7 56223.0 49736.0 50074.0 50143.2 50831.7 51588.2 8135.8 8507.1 8511.6 8965.6 9071.9 32239.8 33319.8 33375.3 35564.0 37145.4 42503.1 42534.4 45098.5 45617.3 46359.8 11362.1 11384.9 11451.0 11459.6 11661.9 12093.4 12121.5 12173.7 12586.4 12637.5 11321.3 11533.9 11656.4 12163.3 12207.1 11816.9 11933.5 12097.3 12178.3 12501.9 11476.5 11742.6 11758.4 11985.8 11994.9 9232.7 9702.6 9877.2 9889.6 9914.8 9057.6 9289.8 9480.5 9855.6 10211.7 16:19:12 INFO - 2015-11-20 16:19:12,929 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:19:12 INFO - 2015-11-20 16:19:12,929 DEBUG : BROWSER_OUTPUT: 16:19:13 INFO - 2015-11-20 16:19:13,606 INFO : Browser exited with error code: 0 16:19:13 INFO - 2015-11-20 16:19:13,624 INFO : Completed test dromaeo_css (00:18:31) 16:19:13 INFO - 2015-11-20 16:19:13,624 INFO : Starting test kraken 16:19:13 INFO - 2015-11-20 16:19:13,624 DEBUG : operating with platform_type : linux_ 16:19:13 INFO - 2015-11-20 16:19:13,625 INFO : Initialising browser for kraken test... 16:19:13 INFO - 2015-11-20 16:19:13,631 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKoXFkG/profile http://localhost:57211/getInfo.html 16:19:17 INFO - 2015-11-20 16:19:17,764 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:19:17 INFO - 2015-11-20 16:19:17,765 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:19:17 INFO - 2015-11-20 16:19:17,765 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:19:17 INFO - 2015-11-20 16:19:17,765 DEBUG : BROWSER_OUTPUT: __metrics 16:19:18 INFO - 2015-11-20 16:19:18,749 INFO : Browser initialized. 16:19:18 INFO - 2015-11-20 16:19:18,750 INFO : Running cycle 1/1 for kraken test... 16:19:18 INFO - 2015-11-20 16:19:18,750 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKoXFkG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:19:30 INFO - 2015-11-20 16:19:30,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 142143488 16:19:30 INFO - 2015-11-20 16:19:30,528 DEBUG : BROWSER_OUTPUT: 16:19:33 INFO - 2015-11-20 16:19:33,003 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57211/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:33 INFO - 2015-11-20 16:19:33,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 142508032 16:19:33 INFO - 2015-11-20 16:19:33,605 DEBUG : BROWSER_OUTPUT: 16:19:36 INFO - 2015-11-20 16:19:36,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57211/tests/kraken/driver.html?testName=audio-dft) 16:19:36 INFO - 2015-11-20 16:19:36,855 DEBUG : BROWSER_OUTPUT: RSS: Main: 142807040 16:19:36 INFO - 2015-11-20 16:19:36,855 DEBUG : BROWSER_OUTPUT: 16:19:39 INFO - 2015-11-20 16:19:39,695 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57211/tests/kraken/driver.html?testName=audio-fft) 16:19:40 INFO - 2015-11-20 16:19:40,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 136728576 16:19:40 INFO - 2015-11-20 16:19:40,276 DEBUG : BROWSER_OUTPUT: 16:19:42 INFO - 2015-11-20 16:19:42,249 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57211/tests/kraken/driver.html?testName=audio-oscillator) 16:19:42 INFO - 2015-11-20 16:19:42,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 137027584 16:19:42 INFO - 2015-11-20 16:19:42,830 DEBUG : BROWSER_OUTPUT: 16:19:45 INFO - 2015-11-20 16:19:45,016 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57211/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:19:45 INFO - 2015-11-20 16:19:45,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 137523200 16:19:45 INFO - 2015-11-20 16:19:45,597 DEBUG : BROWSER_OUTPUT: 16:19:48 INFO - 2015-11-20 16:19:48,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57211/tests/kraken/driver.html?testName=imaging-darkroom) 16:19:49 INFO - 2015-11-20 16:19:49,206 DEBUG : BROWSER_OUTPUT: RSS: Main: 138043392 16:19:49 INFO - 2015-11-20 16:19:49,207 DEBUG : BROWSER_OUTPUT: 16:19:52 INFO - 2015-11-20 16:19:52,629 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57211/tests/kraken/driver.html?testName=imaging-desaturate) 16:19:53 INFO - 2015-11-20 16:19:53,218 DEBUG : BROWSER_OUTPUT: RSS: Main: 139436032 16:19:53 INFO - 2015-11-20 16:19:53,218 DEBUG : BROWSER_OUTPUT: 16:19:56 INFO - 2015-11-20 16:19:56,064 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57211/tests/kraken/driver.html?testName=json-parse-financial) 16:19:56 INFO - 2015-11-20 16:19:56,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 139403264 16:19:56 INFO - 2015-11-20 16:19:56,657 DEBUG : BROWSER_OUTPUT: 16:19:58 INFO - 2015-11-20 16:19:58,247 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57211/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:19:58 INFO - 2015-11-20 16:19:58,820 DEBUG : BROWSER_OUTPUT: RSS: Main: 139894784 16:19:58 INFO - 2015-11-20 16:19:58,820 DEBUG : BROWSER_OUTPUT: 16:20:00 INFO - 2015-11-20 16:20:00,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:01 INFO - 2015-11-20 16:20:01,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 139546624 16:20:01 INFO - 2015-11-20 16:20:01,249 DEBUG : BROWSER_OUTPUT: 16:20:03 INFO - 2015-11-20 16:20:03,327 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:03 INFO - 2015-11-20 16:20:03,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 140083200 16:20:03 INFO - 2015-11-20 16:20:03,908 DEBUG : BROWSER_OUTPUT: 16:20:06 INFO - 2015-11-20 16:20:06,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:06 INFO - 2015-11-20 16:20:06,925 DEBUG : BROWSER_OUTPUT: RSS: Main: 140263424 16:20:06 INFO - 2015-11-20 16:20:06,925 DEBUG : BROWSER_OUTPUT: 16:20:09 INFO - 2015-11-20 16:20:09,500 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:10 INFO - 2015-11-20 16:20:10,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 140443648 16:20:10 INFO - 2015-11-20 16:20:10,082 DEBUG : BROWSER_OUTPUT: 16:20:11 INFO - 2015-11-20 16:20:11,752 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57211/tests/kraken/driver.html?testName=ai-astar) 16:20:11 INFO - 2015-11-20 16:20:11,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 139194368 16:20:11 INFO - 2015-11-20 16:20:11,995 DEBUG : BROWSER_OUTPUT: 16:20:11 INFO - 2015-11-20 16:20:11,997 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:20:11 INFO - 2015-11-20 16:20:11,997 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:20:11 INFO - 2015-11-20 16:20:11,997 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:20:11 INFO - 2015-11-20 16:20:11,997 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:20:11 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;105;111;111;111;125;111;110;110;111 16:20:11 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;125;152;165;128;152;154;132;168;129 16:20:11 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;161;161;164;161;162;165;171;161 16:20:11 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;75;74;72;72;152;72;72;73;73 16:20:11 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;91;136;89;90;91;141;90;91;140 16:20:11 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;110;109;109;110;110;110;110;110 16:20:12 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;146;147;147;147;147;147;146;146 16:20:12 INFO - 2015-11-20 16:20:11,998 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;97;96;98;96;97;96;96 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;62;62;62;64;64;64;63;62;60 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;69;70;69;69;71;69;70 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;86;89;94;86;87;85;85;85;85 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;113;124;117;127;125;127;115;118;120 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;157;148;147;150;149;155;149;150;138 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;66;68;67;67;68;71;68;66;67 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,729 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: __startTimestamp1448065211996__endTimestamp 16:20:12 INFO - 2015-11-20 16:20:11,999 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,003 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:20:12 INFO - 2015-11-20 16:20:12,003 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:20:12 INFO - 2015-11-20 16:20:12,003 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,004 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:5.08 (4.6%) stddev-sans-first:5.36 16:20:12 INFO - 2015-11-20 16:20:12,004 DEBUG : BROWSER_OUTPUT: Values: 110.0 105.0 111.0 111.0 111.0 125.0 111.0 110.0 110.0 111.0 16:20:12 INFO - 2015-11-20 16:20:12,004 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,004 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.50 Median:152.00 stddev:15.79 (10.4%) stddev-sans-first:16.67 16:20:12 INFO - 2015-11-20 16:20:12,004 DEBUG : BROWSER_OUTPUT: Values: 150.0 125.0 152.0 165.0 128.0 152.0 154.0 132.0 168.0 129.0 16:20:12 INFO - 2015-11-20 16:20:12,004 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,005 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:3.08 (1.9%) stddev-sans-first:3.27 16:20:12 INFO - 2015-11-20 16:20:12,005 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 161.0 161.0 164.0 161.0 162.0 165.0 171.0 161.0 16:20:12 INFO - 2015-11-20 16:20:12,005 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,005 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:72.50 stddev:25.07 (34.6%) stddev-sans-first:26.40 16:20:12 INFO - 2015-11-20 16:20:12,005 DEBUG : BROWSER_OUTPUT: Values: 72.0 75.0 74.0 72.0 72.0 152.0 72.0 72.0 73.0 73.0 16:20:12 INFO - 2015-11-20 16:20:12,006 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,006 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.90 Median:90.00 stddev:23.57 (26.2%) stddev-sans-first:24.38 16:20:12 INFO - 2015-11-20 16:20:12,006 DEBUG : BROWSER_OUTPUT: Values: 90.0 91.0 136.0 89.0 90.0 91.0 141.0 90.0 91.0 140.0 16:20:12 INFO - 2015-11-20 16:20:12,006 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,006 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 110.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 146.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 16:20:12 INFO - 2015-11-20 16:20:12,007 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 97.0 96.0 98.0 96.0 97.0 96.0 96.0 16:20:12 INFO - 2015-11-20 16:20:12,008 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,008 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.50 Median:62.50 stddev:1.27 (2.0%) stddev-sans-first:1.33 16:20:12 INFO - 2015-11-20 16:20:12,008 DEBUG : BROWSER_OUTPUT: Values: 62.0 62.0 62.0 62.0 64.0 64.0 64.0 63.0 62.0 60.0 16:20:12 INFO - 2015-11-20 16:20:12,008 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,008 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 16:20:12 INFO - 2015-11-20 16:20:12,008 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 69.0 70.0 69.0 69.0 71.0 69.0 70.0 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:86.00 stddev:2.87 (3.3%) stddev-sans-first:2.98 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 89.0 94.0 86.0 87.0 85.0 85.0 85.0 85.0 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.10 Median:122.00 stddev:5.28 (4.3%) stddev-sans-first:5.27 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: Values: 115.0 113.0 124.0 117.0 127.0 125.0 127.0 115.0 118.0 120.0 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.30 Median:149.50 stddev:5.81 (3.9%) stddev-sans-first:5.33 16:20:12 INFO - 2015-11-20 16:20:12,009 DEBUG : BROWSER_OUTPUT: Values: 140.0 157.0 148.0 147.0 150.0 149.0 155.0 149.0 150.0 138.0 16:20:12 INFO - 2015-11-20 16:20:12,010 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,010 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:1.43 (2.1%) stddev-sans-first:1.51 16:20:12 INFO - 2015-11-20 16:20:12,010 DEBUG : BROWSER_OUTPUT: Values: 67.0 66.0 68.0 67.0 67.0 68.0 71.0 68.0 66.0 67.0 16:20:12 INFO - 2015-11-20 16:20:12,010 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:20:12 INFO - 2015-11-20 16:20:12,010 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2015-11-20 16:20:12,850 INFO : Browser exited with error code: 0 16:20:12 INFO - 2015-11-20 16:20:12,900 INFO : Completed test kraken (00:00:59) 16:20:12 INFO - 2015-11-20 16:20:12,900 INFO : Starting test v8_7 16:20:12 INFO - 2015-11-20 16:20:12,900 DEBUG : operating with platform_type : linux_ 16:20:12 INFO - 2015-11-20 16:20:12,900 INFO : Initialising browser for v8_7 test... 16:20:12 INFO - 2015-11-20 16:20:12,906 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDhoKsB/profile http://localhost:57211/getInfo.html 16:20:17 INFO - 2015-11-20 16:20:17,124 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:20:17 INFO - 2015-11-20 16:20:17,124 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:20:17 INFO - 2015-11-20 16:20:17,124 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:20:17 INFO - 2015-11-20 16:20:17,124 DEBUG : BROWSER_OUTPUT: __metrics 16:20:18 INFO - 2015-11-20 16:20:18,025 INFO : Browser initialized. 16:20:18 INFO - 2015-11-20 16:20:18,026 INFO : Running cycle 1/1 for v8_7 test... 16:20:18 INFO - 2015-11-20 16:20:18,026 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDhoKsB/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:29 INFO - 2015-11-20 16:20:29,843 DEBUG : BROWSER_OUTPUT: RSS: Main: 141598720 16:20:29 INFO - 2015-11-20 16:20:29,844 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,247 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57211/tests/v8_7/run.html (next: http://localhost:57211/tests/v8_7/run.html) 16:20:51 INFO - 2015-11-20 16:20:51,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 138280960 16:20:51 INFO - 2015-11-20 16:20:51,497 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,498 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:20:51 INFO - 2015-11-20 16:20:51,498 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:20:51 INFO - 2015-11-20 16:20:51,498 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:20:51 INFO - 2015-11-20 16:20:51,498 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:20:51 INFO - 2015-11-20 16:20:51,499 DEBUG : BROWSER_OUTPUT: |0;Richards;166.61112962345885 16:20:51 INFO - 2015-11-20 16:20:51,499 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.27071716977505 16:20:51 INFO - 2015-11-20 16:20:51,500 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.50490883590464 16:20:51 INFO - 2015-11-20 16:20:51,500 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5040.201005025126 16:20:51 INFO - 2015-11-20 16:20:51,500 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1050.420168067227 16:20:51 INFO - 2015-11-20 16:20:51,500 DEBUG : BROWSER_OUTPUT: |5;Earley;691.0850034554251 16:20:51 INFO - 2015-11-20 16:20:51,500 DEBUG : BROWSER_OUTPUT: |6;Boyer;12108.43373493976 16:20:51 INFO - 2015-11-20 16:20:51,501 DEBUG : BROWSER_OUTPUT: |7;RegExp;24756.09756097561 16:20:51 INFO - 2015-11-20 16:20:51,501 DEBUG : BROWSER_OUTPUT: |8;Splay;626.5664160401003 16:20:51 INFO - 2015-11-20 16:20:51,501 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 16:20:51 INFO - 2015-11-20 16:20:51,501 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: __startTimestamp1448065251496__endTimestamp 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.61 Median:166.61 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,502 DEBUG : BROWSER_OUTPUT: Values: 166.6 16:20:51 INFO - 2015-11-20 16:20:51,503 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,503 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.27 Median:192.27 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,503 DEBUG : BROWSER_OUTPUT: Values: 192.3 16:20:51 INFO - 2015-11-20 16:20:51,503 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,503 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.50 Median:280.50 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: Values: 280.5 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5040.20 Median:5040.20 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: Values: 5040.2 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1050.42 Median:1050.42 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: Values: 1050.4 16:20:51 INFO - 2015-11-20 16:20:51,504 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:691.09 Median:691.09 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: Values: 691.1 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12108.43 Median:12108.43 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: Values: 12108.4 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24756.10 Median:24756.10 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: Values: 24756.1 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,505 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:626.57 Median:626.57 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,506 DEBUG : BROWSER_OUTPUT: Values: 626.6 16:20:51 INFO - 2015-11-20 16:20:51,506 DEBUG : BROWSER_OUTPUT: 16:20:51 INFO - 2015-11-20 16:20:51,506 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 16:20:51 INFO - 2015-11-20 16:20:51,506 DEBUG : BROWSER_OUTPUT: Values: 4835.7 16:20:51 INFO - 2015-11-20 16:20:51,506 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:20:51 INFO - 2015-11-20 16:20:51,506 DEBUG : BROWSER_OUTPUT: 16:20:52 INFO - 2015-11-20 16:20:52,354 INFO : Browser exited with error code: 0 16:20:52 INFO - 2015-11-20 16:20:52,368 INFO : Completed test v8_7 (00:00:39) 16:20:52 INFO - 2015-11-20 16:20:52,527 INFO : Completed test suite (00:20:10) 16:20:52 INFO - 2015-11-20 16:20:52,527 DEBUG : Working with test: dromaeo_css 16:20:52 INFO - 2015-11-20 16:20:52,527 DEBUG : Generating results file: dromaeo_css 16:20:52 INFO - 2015-11-20 16:20:52,528 DEBUG : Working with test: kraken 16:20:52 INFO - 2015-11-20 16:20:52,528 DEBUG : Generating results file: kraken 16:20:52 INFO - 2015-11-20 16:20:52,529 INFO : javascript benchmark 16:20:52 INFO - 2015-11-20 16:20:52,529 DEBUG : Working with test: v8_7 16:20:52 INFO - 2015-11-20 16:20:52,529 DEBUG : Generating results file: v8_7 16:20:52 INFO - 2015-11-20 16:20:52,529 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:20:52 INFO - 2015-11-20 16:20:52,661 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 16:20:52 INFO - 2015-11-20 16:20:52,661 DEBUG : process_Request line: dromaeo_css 7047.00 graph.html#tests=[[72,64,43]] 16:20:52 INFO - 2015-11-20 16:20:52,661 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:20:52 INFO - 2015-11-20 16:20:52,692 DEBUG : process_Request line: kraken 1513.30 graph.html#tests=[[232,64,43]] 16:20:52 INFO - 2015-11-20 16:20:52,692 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:20:52 INFO - 2015-11-20 16:20:52,725 DEBUG : process_Request line: v8_7 21474.38 graph.html#tests=[[230,64,43]] 16:20:52 INFO - 2015-11-20 16:20:52,726 INFO : javascript benchmark 16:20:52 INFO - 2015-11-20 16:20:52,730 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3201.295714942713, 3246.853891574879, 3255.6093268807745, 3273.9181520461984, 3292.242784159643, 5215.687058776476, 5320.909213611215, 5332.626716129873, 5356.8125115620915, 5392.649477783951, 5298.20526920962, 5360.597955153369, 5371.382291036532, 5411.648242864211, 5416.0, 4915.090708218973, 4989.077020751154, 5035.244713292998, 5050.671706339094, 5075.187969924813, 4931.876703082416, 4999.0501804657, 5016.548510634042, 5023.296738456619, 5029.949700503003, 4822.373091498118, 4926.852194434173, 4935.136351138536, 5001.09980203565, 5007.2489126630935, 4868.391451068616, 4889.975550122263, 4926.187179115442, 4941.975290123562, 4958.132326842788, 4939.506049395068, 4945.505449455061, 4954.0, 4970.925436118461, 4997.725125118117, 5048.924266136011, 5182.222666600002, 5189.065968125736, 5248.475152484759, 5274.182501712241, 5731.541476681856, 5761.9135712964335, 5784.566157538202, 5785.71071446426, 5828.825135245949, 5895.204147440084, 5910.143029260733, 5916.0, 5922.378150294243, 5928.051511758141, 5254.395744489432, 5380.596455265871, 5481.917771233435, 5483.698396588186, 5516.227728118067, 5850.23946886902, 5861.472467477862, 5911.970440147772, 5914.2311499504785, 5927.585069045212, 5744.569157313218, 5746.9712651436475, 5758.683272420057, 5776.393478684761, 5778.0, 5723.484886360247, 5774.711264436762, 5784.479396854219, 5797.420257974169, 5809.448102430347, 5733.254676891977, 5740.339860915965, 5753.741081651336, 5757.050086735641, 5773.88452230953, 5612.074007788669, 5694.971525142348, 5738.913916291259, 5741.770329186786, 5771.509421699118, 4525.366448697185, 4541.72749635023, 4546.568076032771, 4578.542145785462, 4584.060267645124, 4011.919761604752, 4013.4381186633896, 4045.7167998239843, 4056.675465962716, 4076.9388459173138, 5387.3804512481565, 5496.258005169251, 5510.201020852034, 5570.610057296031, 5612.214289999403, 5866.911996320058, 5867.970660146757, 5890.528757699374, 5907.527397808165, 5929.0513517836935, 5590.91613625796, 5604.775808967677, 5608.69152196625, 5617.550595952314, 5618.4662457066515, 4967.552920237196, 4987.775550100254, 5085.415177254589, 5099.490050994945, 5160.174372100446, 5875.41245875409, 5938.6436813791315, 5944.781319829424, 5974.910376344358, 5976.073708575178, 5756.625819321731, 5758.683272419889, 5761.423857614205, 5771.307443106854, 5817.418258174149, 5840.065589505659, 5843.211166492639, 5957.553183511341, 5963.940360596339, 6009.969950150221, 5743.195952566561, 5753.0, 5759.94240057594, 5762.337331206965, 5774.422557744192, 5599.524040456607, 5853.326867410302, 5862.970685146717, 5868.501177399798, 5872.970635146797, 5642.379338272869, 5664.745086471036, 5696.458836410535, 5714.971425142848, 5800.622959507618, 5627.380988091225, 5631.521320687623, 5631.88736225265, 5637.774489020393, 5662.405447428126, 5525.640833345819, 5565.6104072714525, 5579.19101730255, 5590.440955904377, 5590.99362114807, 5509.925564514881, 5638.295213098363, 5646.943530564642, 5710.571707122065, 5754.021816291324, 5568.972155139199, 5574.275344205227, 5616.522595579258, 5620.297462817148, 5621.409751976147], "unit": "ms", "name": "dojo.html", "value": 5357.919254039997}, {"lowerIsBetter": false, "replicates": [531.232223700479, 572.1189368372703, 576.8673205162816, 585.9384764599716, 595.0924839619581, 37467.250654986885, 37624.43563346549, 37692.81153594228, 37812.0, 37891.24217515648, 42611.573884261146, 42917.14165716692, 43204.567954320446, 43245.35131973023, 43267.0, 8829.205371516578, 8831.205191532792, 8913.420627659214, 8950.731478055668, 8987.910120898805, 8599.527026013568, 8789.033206347296, 8810.163034511712, 8811.0, 8823.676448532708, 8740.4755714657, 8799.648014079428, 8809.03100658927, 8828.205461508463, 8834.337424693174, 8703.782405439882, 8843.646254149826, 8886.822263554724, 8888.422252553562, 8916.777080572972, 8682.088380719997, 8846.46921184731, 8875.201231889096, 8882.86675699865, 8892.199702026848, 39755.60244397548, 39833.40249896254, 39854.0, 40001.19997600061, 40214.396784048266, 3281.503575806615, 3300.9834950825334, 3725.5156829612247, 3738.12154143776, 3786.829592668309, 30112.54831177534, 31036.844815775778, 31213.0, 31311.37377252442, 31370.843145784354, 3767.56672982605, 3802.7692674969176, 3957.604239576019, 3985.5217373915025, 4101.2207680540605, 263908.0, 270379.0, 270727.0, 270759.0, 271654.0, 63459.0, 64198.679006605606, 64280.0, 64365.35634643687, 64619.67690161519, 61554.38445615576, 63843.0, 69243.0, 69433.65283173551, 69915.3008469919, 15838.76241856373, 16087.0, 16097.517074487783, 16185.271662775203, 16270.0, 11032.28290161137, 11109.166812489095, 11111.611093611686, 11123.443827808576, 11127.443627818577, 21232.787672123082, 21748.238811641833, 21788.237411690516, 21887.890560547094, 21898.562028759654, 8650.394472386872, 8925.983307511646, 8956.373053886296, 8975.955120224357, 8984.371094023356, 1011.8886922438526, 1045.3362115056993, 1073.8765042020111, 1079.8434227037035, 1126.9492872820742, 28963.275918101794, 29133.708662913523, 29137.708622913924, 29141.27146821304, 29200.853995729885, 1023.5649848814224, 1066.6053560182781, 1081.4106312059946, 1091.3615534912008, 1145.5761368293784, 15201.239938003055, 15241.466548670744, 15338.0, 15523.767143492856, 15531.84468155304, 325820.0, 327151.0, 328344.0, 329222.0, 329417.0, 52990.0, 53364.19953700698, 53723.19415208775, 53801.461985380425, 53810.0, 1353.1610401551102, 1372.4304413668415, 1372.6225288045866, 1378.800073989266, 1383.266868559665, 27947.44105117927, 28620.0, 28880.85559572273, 29247.0, 29290.7070929288, 50031.0, 50838.74580627073, 51259.2311115341, 51283.74358128185, 51324.74337628288, 301613.0, 301770.0, 303142.0, 305192.0, 305400.0, 43319.0, 46151.53848461472, 46487.76756116333, 47406.28890566575, 47922.5207747918, 44607.0, 44611.33083003823, 44635.77682111698, 45152.0, 45533.4466553342, 37316.813415932746, 37428.251434971695, 38335.42496862494, 38380.80809595934, 38892.80553597214, 37141.257174856895, 37419.81290093532, 38376.80811595924, 38390.616093839824, 38481.807590961864, 36958.0, 37179.070523236056, 41211.0, 42161.156776864904, 42230.577694223895, 31537.52693709642, 37043.0, 38209.426858596584, 38724.80637596794, 38888.222235555695, 25814.741852581235, 26356.0, 26387.340316492242, 26465.33836654022, 26484.337891552866], "unit": "ms", "name": "ext.html", "value": 19384.8293917745}, {"lowerIsBetter": false, "replicates": [352.918828669406, 356.91969306905946, 358.6306104712146, 360.49530657080066, 363.36774013217, 16657.666846663076, 17107.23017464214, 17698.203580838875, 17706.46880593581, 18124.63750724985, 10741.409222492759, 11897.58358457452, 17234.741478877826, 17264.80498086388, 17415.0, 4737.194676904917, 4870.805167793284, 4884.6811360932525, 4885.071836351082, 4893.2170852663585, 4848.296996935443, 4863.14894893394, 4902.632302577304, 4906.313116163722, 4906.730129842857, 4770.642201834877, 5009.173486374762, 5018.698878067309, 5038.244263360507, 5199.532042116209, 5240.9213861792105, 5270.789168433258, 5308.256844041837, 5323.574114070865, 5330.28041214435, 5082.440931497532, 5342.946570534284, 5344.225087362311, 5345.812896548641, 5358.2498450217, 12230.14388992771, 12407.0, 14889.47886823956, 20178.0, 20237.696434553494, 1947.5033866363985, 2034.7965203479682, 2056.4550394145563, 2076.979230207709, 2140.3578926322043, 9053.230475409599, 9101.317401194938, 9437.528123593862, 9530.094641009093, 9726.27052971023, 1861.8324350808493, 1881.0594702648675, 1886.8018858019984, 1887.1319193171157, 1935.0808366026165, 61905.38094619041, 64656.676716616115, 66685.3331466684, 67211.99182012274, 67602.9999999995, 9733.805323893483, 9878.802423951482, 9980.600775969026, 10075.496225188712, 10084.546195421224, 13111.344432778324, 13112.803307950388, 13117.803232951514, 13119.540816071392, 13129.540466083832, 7107.147142342952, 7126.35862772353, 7178.102737157856, 7261.636918154071, 7270.854582908312, 6868.244493105754, 6884.0, 6906.930930690629, 6954.3045695431065, 6956.991236270713, 4233.851815186454, 4262.7016108872085, 4301.0, 4301.666483390159, 4385.561443855589, 1746.2054765081857, 1747.8339557741995, 1755.4558086992818, 1762.3303144805147, 1787.3118849243135, 403.2036727463295, 417.22812796326554, 431.8207943703328, 441.3357896365949, 969.2778879734502, 10149.39103653783, 10271.537780799881, 10324.845127323097, 10435.634752783617, 10461.372317660966, 439.0319345842474, 451.92091384007665, 457.32772823948744, 863.3007264116086, 955.7562821480478, 8883.955580222058, 9071.909280907106, 9256.444613323221, 9272.258219342437, 9376.484293363794, 512383.0, 514070.0, 515440.0, 518748.0, 523398.06407744024, 79876.20123798688, 82488.0, 83188.0, 85247.14752852391, 87833.12166878377, 1134.0304040045642, 1137.4710759496875, 1142.8914253145938, 1168.7428765671539, 1169.1348402182493, 10214.0, 15307.540773776804, 15558.533244002698, 15655.765163522328, 15841.128737919526, 35912.64087359093, 35949.28101437904, 36469.63530364769, 37246.44130337993, 37594.06014849541, 46208.76895615501, 47608.76195619, 49739.253911192085, 50938.490615093375, 54387.72806135944, 15116.697666046397, 15151.848481515044, 15229.0, 15248.466303679317, 15340.38638454494, 18437.354692585694, 18512.352067677566, 18845.24619015224, 19083.90458047701, 19233.65364424477, 9446.0, 9906.108450239513, 15522.84477155214, 15819.446319378767, 15820.60448488797, 9931.851022234529, 10053.0, 10204.38773673582, 10282.485875706036, 10333.689989300334, 9501.477418742035, 9954.253430992849, 9989.850152247576, 10043.548040338057, 10243.129334006693, 14831.258437078319, 14895.0, 14979.700405991602, 15106.093634381972, 15282.0, 15568.688626227638, 15747.763783543027, 15997.600059998593, 16090.436834710728, 16324.428644997368], "unit": "ms", "name": "jquery.html", "value": 8444.433114439782}, {"lowerIsBetter": false, "replicates": [1492.1345619540668, 1668.7580300856375, 1719.7592337072813, 1735.887167334124, 1737.774089368382, 7079.398251148652, 7459.0, 7516.022917020787, 7573.659185336659, 7697.846043079149, 7610.972518709972, 7621.314081732643, 7687.923120768791, 7691.1924247954, 7701.576413297266, 2302.7351854536773, 2344.050659482912, 2351.2123438648073, 2352.9058837646558, 2353.9646905296436, 2311.2603966730653, 2342.683737695415, 2349.706286714161, 2356.5051339218785, 2357.917472888449, 2214.236088549456, 2241.753407125223, 2251.4821591034074, 2251.7297924249115, 2259.2770313499686, 2315.6526521021897, 2338.298510446859, 2339.3332900123382, 2340.3447034830274, 2342.2387723989686, 2251.5609456156058, 2313.063209400196, 2322.790948814598, 2329.0450915124716, 2332.661764044221, 7406.888896666501, 7439.181690014094, 7455.2172021938, 7458.776236712907, 7489.063867016623, 4542.068875880436, 4547.47704014036, 4608.446986361658, 4620.8151673933, 4625.051864367797, 2962.1113665900143, 2987.208389776711, 3015.638123425181, 3017.9396412071637, 3024.395120975814, 981.4160574458186, 982.5332966840693, 996.043797953965, 1001.4792307999872, 1028.3469996552244, 10118.595256189743, 10132.594696212143, 10180.694579162562, 10198.643047493302, 10215.182785377152, 4077.89805254871, 4120.505539335299, 4138.772367519815, 4160.45914031174, 4161.791910404468, 8275.917240827635, 8295.377846661524, 8333.66665333392, 8336.458130221516, 8371.95814020926, 4478.0, 4494.775261236925, 4506.301523263899, 4535.138323718549, 4565.406497155349, 4151.920500669848, 4170.72890262132, 4193.853215137456, 4194.559571244975, 4211.010412553048, 3468.3930312195266, 3497.0557949353533, 3525.1892064825233, 3539.3983022886173, 3542.822858857047, 1108.4734750993375, 1119.1326721790515, 1120.1822669451346, 1122.2144498850837, 1123.3821398230941, 1064.3188359449957, 1066.258950029722, 1080.443571560647, 1221.2550344289975, 1331.7536255792709, 6638.0, 6810.897836532456, 6819.5908245505425, 6852.109225800614, 6854.0404343391965, 1136.8635763708241, 1144.8053830848776, 1149.804533229353, 1153.325304696745, 1160.4255893332854, 5380.811671591476, 5399.676019438846, 5405.054115529767, 5436.157395603688, 5440.972795135999, 8729.0, 8749.56252187388, 8914.242289405473, 9010.864837027319, 9055.411398259092, 8958.86561701575, 8965.686200982935, 8971.641134354553, 8974.282057435521, 8995.55022248885, 6674.933250667528, 6726.7981960541265, 6729.6971636276485, 6917.996890450857, 6927.618980955995, 6441.710123044568, 6461.644609546332, 6469.514786390945, 6493.123428337114, 6494.935050649433, 7497.88753168692, 7651.081870175614, 7716.729914452967, 7837.37301015906, 7936.761897143095, 7778.0666320041955, 7903.249191326815, 7906.723264685708, 7919.762407127795, 7984.760457186294, 6643.534952553275, 6685.799426017227, 6706.362895524917, 6737.393634572912, 6760.256371799196, 6967.303269673195, 7040.119985001875, 7040.929590704028, 7054.224035356209, 7064.152301723826, 6503.057056726842, 6579.80260592183, 6592.143021407186, 6595.703193356215, 6870.416014638612, 6376.171097757262, 6508.088867558451, 6508.73965041412, 6535.607863528204, 6603.174603174603, 6443.420092191725, 6483.286838447672, 6720.361565651256, 6726.899096513458, 6760.323967603397, 6037.245344331958, 6058.0, 6087.330393656783, 6091.451769340781, 6110.327863935053, 6069.0, 6084.361142080018, 6117.235345581802, 6122.877542449037, 6125.111858780363], "unit": "ms", "name": "mootools.html", "value": 4338.988148607412}, {"lowerIsBetter": false, "replicates": [316.40200021958503, 333.4964204051882, 341.89230392426384, 343.50878244110925, 346.11049602521496, 6387.840303992397, 6391.680415979194, 6407.391297826704, 6417.614943103417, 6465.159529261189, 6230.750769969196, 6307.400796924297, 6325.399087086731, 6342.746290148389, 6342.873142537146, 4833.806647734086, 4835.516448355172, 4839.443464001641, 4859.854204373874, 4873.147199240118, 4851.757412129397, 4855.077535268305, 4856.150173719585, 4860.4896485869, 4860.6111511079025, 4682.344471773954, 4765.451973023095, 4784.5693887550115, 4799.856004319876, 4801.663883528155, 4772.284157376386, 4783.497732738065, 4798.592119669832, 4801.831935882262, 4819.108464933998, 4164.708470407073, 4175.770332631704, 4177.060161463677, 4178.707490475668, 4180.352045432963, 5959.165716799652, 5971.223740913653, 5996.700164991733, 6006.069059295773, 6009.128676341906, 5501.367342755594, 5588.133839254922, 5634.267545219136, 5646.632968857011, 5659.547236221092, 6717.193936727575, 6728.158980127484, 6728.966355168192, 6733.158355205599, 6754.3921047105505, 4616.146012987608, 4627.097715945392, 4652.371929789503, 4660.37084993525, 4669.509701481364, 6785.898211526831, 6923.861522769566, 6951.339622735832, 7033.50765446419, 7041.753538626123, 4601.562851529099, 4623.86128416148, 4625.121226966866, 4628.606568441653, 4631.027484228282, 4673.228917228687, 4674.322223277606, 4676.836310729108, 4680.08738296029, 4686.789094490756, 5515.7242137892945, 5521.061419558685, 5522.91715624266, 5527.53015993637, 5530.142827861688, 6596.538242323088, 6721.1598550181225, 6754.223264324665, 6782.118324617867, 6812.659367031728, 1673.238676402234, 1697.8726595505386, 1699.2947926610566, 1702.182952182959, 1704.880658353928, 1140.674907651332, 1152.072581571826, 1157.7973854575584, 1165.0504838556549, 1165.5454372794618, 6322.304546499881, 6447.580907241015, 6448.419642232222, 6452.0967064610995, 6463.64449955257, 6789.932100679029, 6792.76225332111, 6852.0, 6857.622830744257, 6863.039174515572, 6338.714757835908, 6373.5857169283845, 6432.099506069154, 6443.48452123829, 6444.291127975919, 3816.713746469026, 3845.7692538447222, 3857.5756666766633, 3859.3053250415196, 3861.555921069113, 7378.188399276075, 7407.2222416646555, 7407.370373518203, 7420.406367490587, 7423.665935032936, 7056.400205982446, 7232.168300645388, 7291.744788932362, 7305.707771689179, 7315.378192853667, 7296.927030729731, 7308.574827908506, 7381.37258333037, 7401.666924988388, 7413.703451861973, 6999.860002800017, 7038.8944165838575, 7066.4346852250665, 7079.504434689522, 7095.467839911924, 6872.175338959357, 6911.17065952089, 6986.6855991481525, 6991.126109236346, 6994.930050699428, 7309.853802924018, 7330.633468326456, 7336.046313979148, 7336.193018768038, 7343.045404097433, 4666.883327916829, 4712.198926182626, 4724.574788268935, 4729.69256998294, 4750.334953106499, 6848.315168483311, 6867.141607299088, 7011.193712723102, 7030.578165310098, 7035.437165026683, 6766.255711871789, 6821.590704557742, 6830.931690683228, 6849.794506164823, 6863.313668633096, 6811.250762416031, 6815.0, 6827.6244806536115, 6828.556143850634, 6832.555883867532, 6571.0, 6629.535932484872, 6660.167479065117, 6676.399124078662, 6707.966460167668, 6805.727770889109, 6823.692933817891, 6846.623435710884, 6850.451963842781, 6878.3465570770695, 6584.9670751647855, 6591.967040164768, 6614.908540090831, 6616.305287944697, 6618.470522358296], "unit": "ms", "name": "prototype.html", "value": 5075.099657715107}, {"lowerIsBetter": false, "replicates": [769.8575763483759, 819.9508029518229, 821.8685010398334, 827.8302947895681, 848.4442690038026, 11748.823767643482, 11931.821022684666, 11963.0, 11970.34163121028, 11990.0, 11713.414329283523, 11736.413179341042, 11789.351585662786, 11795.233309834875, 12421.627351179479, 810.5217921426372, 816.346922462031, 816.7182322098867, 817.8159914019358, 817.9591020448984, 798.3134504326304, 799.6481548118838, 800.0319613267924, 804.0833449867156, 804.5896592737687, 845.18439705684, 846.5597889097666, 847.4576271186446, 847.8346722389136, 851.8126012277289, 846.8645016797313, 847.7499137754346, 848.3722045686147, 848.6647774129216, 851.2721623012297, 624.9125122482856, 625.527726566444, 628.676231740654, 628.7139351595059, 629.9055141728732, 12718.364081795966, 12746.235225886476, 12818.102732808708, 12838.486460541568, 12878.0, 11374.431278436045, 11625.6512304631, 11679.182457227997, 11781.41092945358, 12094.697632559168, 9961.601535938555, 9998.05018523239, 10034.09693127622, 10113.797724045478, 10168.644097456554, 3264.7877887937207, 3269.3461307738307, 3280.6391296957318, 3316.9385796545116, 3323.0861513083855, 48399.0, 48689.0, 48839.26741098886, 48935.26597101046, 49056.26415603769, 9417.62329506812, 9601.32790704644, 9706.272029597809, 9709.36889102206, 9808.656697015713, 17836.91081544584, 17924.91037544804, 17967.1016449177, 17993.100344982697, 17999.64000719978, 7489.176190619028, 7547.0, 7651.387888968869, 7793.337566306814, 7946.205379462123, 4349.739015659071, 4413.823447062082, 4418.955810441918, 4422.425084738963, 4431.955680443219, 3073.1241596145437, 3111.7510599152015, 3121.500559910449, 3126.155937896755, 3127.03062050765, 528.7858417340983, 540.9053415652245, 541.2098336428858, 542.2950164785763, 548.8463230295523, 570.4067769519736, 674.8380388706676, 700.1458220970408, 700.3276854220006, 714.6569646569676, 8911.197992180734, 9326.95336523313, 9368.95315523418, 9527.714168574954, 9715.22278217755, 606.2452246952572, 685.5578152091873, 703.7339469972554, 714.2536049827942, 715.2418436457372, 4000.8599699010397, 4146.232946904832, 4196.853110141131, 4208.747475151501, 4276.935845962313, 61403.6929815357, 63230.36769632337, 63248.367516324244, 63349.36650633527, 63439.68280158662, 53702.731486342316, 53977.0, 54895.45104548983, 55623.72188139033, 56223.0, 49736.0, 50074.0, 50143.24785128226, 50831.74584127056, 51588.22617660738, 8135.77559366931, 8507.106753790762, 8511.574421278787, 8965.551722413722, 9071.863922041042, 32239.838800805846, 33319.83340083284, 33375.33249334951, 35564.0, 37145.442818358286, 42503.149937001705, 42534.36198456964, 45098.54901450944, 45617.31574026326, 46359.768201158775, 11362.068448363963, 11384.943075284571, 11451.0, 11459.598914037968, 11661.883381166079, 12093.395330233277, 12121.454534546143, 12173.69565760863, 12586.370681465707, 12637.494500220257, 11321.31670000947, 11533.88466115328, 11656.358900260568, 12163.2702037878, 12207.145499814927, 11816.881831181578, 11933.46299416511, 12097.274163550215, 12178.269303841798, 12501.937490312854, 11476.483558240066, 11742.647720568397, 11758.3532905691, 11985.820212696643, 11994.940025299818, 9232.676856309998, 9702.6118955241, 9877.209823213982, 9889.554970026222, 9914.752131196778, 9057.637694492147, 9289.767755806159, 9480.525973701426, 9855.556499957376, 10211.7447063824], "unit": "ms", "name": "yui.html", "value": 6340.506666657892}], "name": "dromaeo_css", "value": 7046.998081854399}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 105.0, 111.0, 111.0, 111.0, 125.0, 111.0, 110.0, 110.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 111.5}, {"lowerIsBetter": true, "replicates": [150.0, 125.0, 152.0, 165.0, 128.0, 152.0, 154.0, 132.0, 168.0, 129.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.5}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 161.0, 161.0, 164.0, 161.0, 162.0, 165.0, 171.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 163.2}, {"lowerIsBetter": true, "replicates": [72.0, 75.0, 74.0, 72.0, 72.0, 152.0, 72.0, 72.0, 73.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [90.0, 91.0, 136.0, 89.0, 90.0, 91.0, 141.0, 90.0, 91.0, 140.0], "unit": "ms", "name": "audio-oscillator", "value": 104.9}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 98.0, 96.0, 97.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 62.0, 62.0, 64.0, 64.0, 64.0, 63.0, 62.0, 60.0], "unit": "ms", "name": "json-parse-financial", "value": 62.5}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 69.0, 70.0, 69.0, 69.0, 71.0, 69.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 89.0, 94.0, 86.0, 87.0, 85.0, 85.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.7}, {"lowerIsBetter": true, "replicates": [115.0, 113.0, 124.0, 117.0, 127.0, 125.0, 127.0, 115.0, 118.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.1}, {"lowerIsBetter": true, "replicates": [140.0, 157.0, 148.0, 147.0, 150.0, 149.0, 155.0, 149.0, 150.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.3}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 68.0, 67.0, 67.0, 68.0, 71.0, 68.0, 66.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.5}], "name": "kraken", "value": 1513.2999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.61112962345885], "unit": "score", "name": "Richards", "value": 211.88260400000004}, {"lowerIsBetter": false, "replicates": [192.27071716977505], "unit": "score", "name": "DeltaBlue", "value": 343.87971799999985}, {"lowerIsBetter": false, "replicates": [280.50490883590464], "unit": "score", "name": "Encrypt", "value": 948.9352650000003}, {"lowerIsBetter": false, "replicates": [5040.201005025126], "unit": "score", "name": "Decrypt", "value": 52.81158424725827}, {"lowerIsBetter": false, "replicates": [1050.420168067227], "unit": "score", "name": "RayTrace", "value": 704.469528}, {"lowerIsBetter": false, "replicates": [691.0850034554251], "unit": "score", "name": "Earley", "value": 964.371961}, {"lowerIsBetter": false, "replicates": [12108.43373493976], "unit": "score", "name": "Boyer", "value": 55.0412228855721}, {"lowerIsBetter": false, "replicates": [24756.09756097561], "unit": "score", "name": "RegExp", "value": 36.79840886699505}, {"lowerIsBetter": false, "replicates": [626.5664160401003], "unit": "score", "name": "Splay", "value": 130.05963599999995}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21474.382256413453}]} 16:20:52 INFO - RETURN: dromaeo_css: 7047.00 16:20:52 INFO - RETURN: kraken: 1513.30 16:20:52 INFO - RETURN: v8_7: 21474.38 16:20:52 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21474.38"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1513.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7047.00"}}} 16:20:52 INFO - Return code: 0 16:20:52 INFO - # TBPL SUCCESS # 16:20:52 INFO - Running post-action listener: _resource_record_post_action 16:20:52 INFO - Running post-run listener: _resource_record_post_run 16:20:53 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 4096; Write bytes: 513495040; Read time: 8; Write time: 436944 16:20:53 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 163926016; Read time: 0; Write time: 232428 16:20:53 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 4096; Write bytes: 344899584; Read time: 8; Write time: 204248 16:20:53 INFO - Running post-run listener: _upload_blobber_files 16:20:53 INFO - Blob upload gear active. 16:20:53 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:20:53 INFO - Copying logs to upload dir... 16:20:53 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.124381 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2015-11-20 16:20:53.161774) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 16:20:53.165520) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 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.009905 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:53.217052) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 16:20:53.217381) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1448063998.245097-1348697321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 16:20:53.268177) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 16:20:53.268504) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 16:20:53.268791) ========= ========= Total master_lag: 0.20 =========