builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-068 starttime: 1448290012.15 results: success (0) buildid: 20151123043004 builduid: 72cd1c31b1d6414ca7a21bcbfac58212 revision: b9b6a1567ef6b27735e19b661e895753db58e902 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.154938) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.155555) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.155870) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006650 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.178913) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.179263) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.192313) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.192872) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004585 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.242492) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.242927) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.243304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.243570) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 06:46:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2015-11-23 06:46:53 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.432299) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.432620) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.482927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:52.483372) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev b9b6a1567ef6b27735e19b661e895753db58e902 --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/mozilla-inbound --rev b9b6a1567ef6b27735e19b661e895753db58e902 --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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 06:46:53,979 truncating revision to first 12 chars 2015-11-23 06:46:53,979 Setting DEBUG logging. 2015-11-23 06:46:53,979 attempt 1/10 2015-11-23 06:46:53,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/b9b6a1567ef6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 06:46:54,229 unpacking tar archive at: mozilla-inbound-b9b6a1567ef6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.524684 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:53.021311) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:53.021659) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:53.028527) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:53.028840) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 06:46:53.029326) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-23 06:46:53.029647) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:46:54 INFO - MultiFileLogger online at 20151123 06:46:54 in /builds/slave/test-pgo 06:46:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 06:46:54 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:46:54 INFO - {'append_to_log': False, 06:46:54 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:46:54 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 06:46:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:46:54 INFO - 'branch': 'Mozilla-Inbound', 06:46:54 INFO - 'buildbot_json_path': 'buildprops.json', 06:46:54 INFO - 'config_files': ('talos/linux_config.py',), 06:46:54 INFO - 'default_actions': ('clobber', 06:46:54 INFO - 'read-buildbot-config', 06:46:54 INFO - 'download-and-extract', 06:46:54 INFO - 'populate-webroot', 06:46:54 INFO - 'create-virtualenv', 06:46:54 INFO - 'install', 06:46:54 INFO - 'run-tests'), 06:46:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:46:54 INFO - 'download_minidump_stackwalk': True, 06:46:54 INFO - 'download_symbols': 'ondemand', 06:46:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:46:54 INFO - 'tooltool.py': '/tools/tooltool.py', 06:46:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:46:54 INFO - '/tools/misc-python/virtualenv.py')}, 06:46:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:46:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:46:54 INFO - 'installer_path': 'installer.exe', 06:46:54 INFO - 'log_level': 'info', 06:46:54 INFO - 'log_name': 'talos', 06:46:54 INFO - 'log_to_console': True, 06:46:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:46:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:46:54 INFO - 'opt_config_files': (), 06:46:54 INFO - 'pip_index': False, 06:46:54 INFO - 'sps_profile': False, 06:46:54 INFO - 'sps_profile_interval': 0, 06:46:54 INFO - 'suite': 'dromaeojs-e10s', 06:46:54 INFO - 'system_bits': '64', 06:46:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:46:54 INFO - 'title': 'talos-linux64-ix-068', 06:46:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:46:54 INFO - 'use_talos_json': True, 06:46:54 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:46:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:46:54 INFO - 'work_dir': 'build'} 06:46:54 INFO - ##### 06:46:54 INFO - ##### Running clobber step. 06:46:54 INFO - ##### 06:46:54 INFO - Running pre-action listener: _resource_record_pre_action 06:46:54 INFO - Running main action method: clobber 06:46:54 INFO - rmtree: /builds/slave/test-pgo/build 06:46:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 06:46:55 INFO - Running post-action listener: _resource_record_post_action 06:46:55 INFO - ##### 06:46:55 INFO - ##### Running read-buildbot-config step. 06:46:55 INFO - ##### 06:46:55 INFO - Running pre-action listener: _resource_record_pre_action 06:46:55 INFO - Running main action method: read_buildbot_config 06:46:55 INFO - Using buildbot properties: 06:46:55 INFO - { 06:46:55 INFO - "properties": { 06:46:55 INFO - "buildnumber": 460, 06:46:55 INFO - "product": "firefox", 06:46:55 INFO - "script_repo_revision": "production", 06:46:55 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 06:46:55 INFO - "repository": "", 06:46:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 06:46:55 INFO - "buildid": "20151123043004", 06:46:55 INFO - "slavename": "talos-linux64-ix-068", 06:46:55 INFO - "pgo_build": "True", 06:46:55 INFO - "basedir": "/builds/slave/test-pgo", 06:46:55 INFO - "project": "", 06:46:55 INFO - "platform": "ubuntu64_hw", 06:46:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:46:55 INFO - "slavebuilddir": "test", 06:46:55 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 06:46:55 INFO - "branch": "mozilla-inbound", 06:46:55 INFO - "repo_path": "integration/mozilla-inbound", 06:46:55 INFO - "stage_platform": "linux64-pgo", 06:46:55 INFO - "builduid": "72cd1c31b1d6414ca7a21bcbfac58212", 06:46:55 INFO - "revision": "b9b6a1567ef6b27735e19b661e895753db58e902" 06:46:55 INFO - }, 06:46:55 INFO - "sourcestamp": { 06:46:55 INFO - "repository": "", 06:46:55 INFO - "hasPatch": false, 06:46:55 INFO - "project": "", 06:46:55 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 06:46:55 INFO - "changes": [ 06:46:55 INFO - { 06:46:55 INFO - "category": null, 06:46:55 INFO - "files": [ 06:46:55 INFO - { 06:46:55 INFO - "url": null, 06:46:55 INFO - "name": "https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 06:46:55 INFO - } 06:46:55 INFO - ], 06:46:55 INFO - "repository": "", 06:46:55 INFO - "rev": "b9b6a1567ef6b27735e19b661e895753db58e902", 06:46:55 INFO - "who": "sendchange", 06:46:55 INFO - "when": 1448289918, 06:46:55 INFO - "number": 6695037, 06:46:55 INFO - "comments": "", 06:46:55 INFO - "project": "", 06:46:55 INFO - "at": "Mon 23 Nov 2015 06:45:18", 06:46:55 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 06:46:55 INFO - "revlink": "", 06:46:55 INFO - "properties": [ 06:46:55 INFO - [ 06:46:55 INFO - "buildid", 06:46:55 INFO - "20151123043004", 06:46:55 INFO - "Change" 06:46:55 INFO - ], 06:46:55 INFO - [ 06:46:55 INFO - "builduid", 06:46:55 INFO - "72cd1c31b1d6414ca7a21bcbfac58212", 06:46:55 INFO - "Change" 06:46:55 INFO - ], 06:46:55 INFO - [ 06:46:55 INFO - "pgo_build", 06:46:55 INFO - "True", 06:46:55 INFO - "Change" 06:46:55 INFO - ] 06:46:55 INFO - ], 06:46:55 INFO - "revision": "b9b6a1567ef6b27735e19b661e895753db58e902" 06:46:55 INFO - } 06:46:55 INFO - ], 06:46:55 INFO - "revision": "b9b6a1567ef6b27735e19b661e895753db58e902" 06:46:55 INFO - } 06:46:55 INFO - } 06:46:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 06:46:55 INFO - Running post-action listener: _resource_record_post_action 06:46:55 INFO - ##### 06:46:55 INFO - ##### Running download-and-extract step. 06:46:55 INFO - ##### 06:46:55 INFO - Running pre-action listener: _resource_record_pre_action 06:46:55 INFO - Running main action method: download_and_extract 06:46:55 INFO - mkdir: /builds/slave/test-pgo/build/tests 06:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:55 INFO - https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:46:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/test_packages.json 06:46:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/test_packages.json 06:46:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 06:46:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 06:46:57 INFO - Downloaded 1302 bytes. 06:46:57 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:46:57 INFO - Using the following test package requirements: 06:46:57 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:46:57 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:46:57 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'jsshell-linux-x86_64.zip'], 06:46:57 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:46:57 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:46:57 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:46:57 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:46:57 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:46:57 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 06:46:57 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 06:46:57 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:46:57 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:57 INFO - https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:46:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:46:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:46:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:46:58 INFO - Downloaded 22525614 bytes. 06:46:58 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 06:46:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:46:58 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 06:46:59 INFO - Return code: 0 06:46:59 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:59 INFO - https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:46:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:46:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:46:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:46:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:47:01 INFO - Downloaded 22525614 bytes. 06:47:01 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 06:47:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 06:47:01 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 06:47:02 INFO - Return code: 0 06:47: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')]} 06:47:02 INFO - https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:47:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:47:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:47:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:47:03 INFO - Downloaded 11130997 bytes. 06:47:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 06:47:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 06:47:03 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 06:47:04 INFO - Return code: 0 06:47: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')]} 06:47:04 INFO - https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:47:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:47:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:47:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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 06:47:07 INFO - Downloaded 57548813 bytes. 06:47:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:47:07 INFO - mkdir: /builds/slave/test-pgo/properties 06:47:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:47:07 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:47:07 INFO - Contents: 06:47:07 INFO - build_url:https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 06:47:07 INFO - Running post-action listener: _resource_record_post_action 06:47:07 INFO - Running post-action listener: set_extra_try_arguments 06:47:07 INFO - ##### 06:47:07 INFO - ##### Running populate-webroot step. 06:47:07 INFO - ##### 06:47:07 INFO - Running pre-action listener: _resource_record_pre_action 06:47:07 INFO - Running main action method: populate_webroot 06:47:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:47:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:47:07 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 06:47:07 INFO - '../fennec_ids.txt'], 06:47:07 INFO - 'tests': ['tcheck2']}, 06:47:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:47:07 INFO - '--tppagecycles', 06:47:07 INFO - '7'], 06:47:07 INFO - 'tests': ['tsvgm']}}, 06:47:07 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:47:07 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:47:07 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:47:07 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:47:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:47:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:47:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'tests': ['damp', 'tps']}, 06:47:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['damp', 'tps']}, 06:47:07 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:47:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:47:07 INFO - 'other': {'tests': ['a11yr', 06:47:07 INFO - 'ts_paint', 06:47:07 INFO - 'tpaint', 06:47:07 INFO - 'sessionrestore', 06:47:07 INFO - 'sessionrestore_no_auto_restore']}, 06:47:07 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['a11yr', 06:47:07 INFO - 'ts_paint', 06:47:07 INFO - 'tpaint', 06:47:07 INFO - 'sessionrestore', 06:47:07 INFO - 'sessionrestore_no_auto_restore']}, 06:47:07 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['a11yr', 06:47:07 INFO - 'ts_paint', 06:47:07 INFO - 'tpaint', 06:47:07 INFO - 'sessionrestore', 06:47:07 INFO - 'sessionrestore_no_auto_restore']}, 06:47:07 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['a11yr', 06:47:07 INFO - 'ts_paint', 06:47:07 INFO - 'tpaint', 06:47:07 INFO - 'sessionrestore', 06:47:07 INFO - 'sessionrestore_no_auto_restore']}, 06:47:07 INFO - 'other_l64': {'tests': ['a11yr', 06:47:07 INFO - 'ts_paint', 06:47:07 INFO - 'tpaint', 06:47:07 INFO - 'sessionrestore', 06:47:07 INFO - 'sessionrestore_no_auto_restore']}, 06:47:07 INFO - 'other_nol64': {'tests': ['a11yr', 06:47:07 INFO - 'ts_paint', 06:47:07 INFO - 'tpaint', 06:47:07 INFO - 'sessionrestore', 06:47:07 INFO - 'sessionrestore_no_auto_restore']}, 06:47:07 INFO - 'svgr': {'tests': ['tsvgx', 06:47:07 INFO - 'tsvgr_opacity', 06:47:07 INFO - 'tart', 06:47:07 INFO - 'tscrollx', 06:47:07 INFO - 'cart']}, 06:47:07 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['tsvgx', 06:47:07 INFO - 'tsvgr_opacity', 06:47:07 INFO - 'tart', 06:47:07 INFO - 'tscrollx', 06:47:07 INFO - 'cart']}, 06:47:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'tests': ['tp5o']}, 06:47:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'talos_options': ['--e10s'], 06:47:07 INFO - 'tests': ['tp5o']}, 06:47:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'talos_options': ['--xperf_path', 06:47:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:47:07 INFO - 'tests': ['tp5n']}, 06:47:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:07 INFO - 'talos_options': ['--e10s', 06:47:07 INFO - '--xperf_path', 06:47:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:47:07 INFO - 'tests': ['tp5n']}}, 06:47:07 INFO - 'talos.zip': {'path': '', 06:47:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:47:07 INFO - Running post-action listener: _resource_record_post_action 06:47:07 INFO - ##### 06:47:07 INFO - ##### Running create-virtualenv step. 06:47:07 INFO - ##### 06:47:07 INFO - Running pre-action listener: _resource_record_pre_action 06:47:07 INFO - Running main action method: create_virtualenv 06:47:07 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:47:07 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 06:47:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:47:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:47:07 INFO - Using real prefix '/usr' 06:47:07 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:47:08 INFO - Installing distribute.............................................................................................................................................................................................done. 06:47:10 INFO - Installing pip.................done. 06:47:10 INFO - Return code: 0 06:47:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 06:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 06:47:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:47:10 INFO - Using env: {'DISPLAY': ':0', 06:47:10 INFO - 'HOME': '/home/cltbld', 06:47:10 INFO - 'LANG': 'en_US.UTF-8', 06:47:10 INFO - 'LANGUAGE': 'en_US:en', 06:47:10 INFO - 'LOGNAME': 'cltbld', 06:47:10 INFO - 'MAIL': '/var/mail/cltbld', 06:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:10 INFO - 'MOZ_NO_REMOTE': '1', 06:47:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:10 INFO - 'NO_EM_RESTART': '1', 06:47:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:10 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:10 INFO - 'SHELL': '/bin/bash', 06:47:10 INFO - 'SHLVL': '1', 06:47:10 INFO - 'TERM': 'linux', 06:47:10 INFO - 'TMOUT': '86400', 06:47:10 INFO - 'USER': 'cltbld', 06:47:10 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:10 INFO - '_': '/tools/buildbot/bin/python'} 06:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:10 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 06:47:10 INFO - Cleaning up... 06:47:10 INFO - Return code: 0 06:47:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 06:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 06:47:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 06:47:10 INFO - Using env: {'DISPLAY': ':0', 06:47:10 INFO - 'HOME': '/home/cltbld', 06:47:10 INFO - 'LANG': 'en_US.UTF-8', 06:47:10 INFO - 'LANGUAGE': 'en_US:en', 06:47:10 INFO - 'LOGNAME': 'cltbld', 06:47:10 INFO - 'MAIL': '/var/mail/cltbld', 06:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:10 INFO - 'MOZ_NO_REMOTE': '1', 06:47:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:10 INFO - 'NO_EM_RESTART': '1', 06:47:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:10 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:10 INFO - 'SHELL': '/bin/bash', 06:47:10 INFO - 'SHLVL': '1', 06:47:10 INFO - 'TERM': 'linux', 06:47:10 INFO - 'TMOUT': '86400', 06:47:10 INFO - 'USER': 'cltbld', 06:47:10 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:10 INFO - '_': '/tools/buildbot/bin/python'} 06:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:10 INFO - Downloading/unpacking psutil>=0.7.1 06:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:12 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 06:47:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 06:47:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:47:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:47:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:47:12 INFO - Installing collected packages: psutil 06:47:12 INFO - Running setup.py install for psutil 06:47:12 INFO - building 'psutil._psutil_linux' extension 06:47:12 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 06:47:12 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 06:47:12 INFO - building 'psutil._psutil_posix' extension 06:47:12 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 06:47:12 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 06:47:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:47:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:47:12 INFO - Successfully installed psutil 06:47:12 INFO - Cleaning up... 06:47:12 INFO - Return code: 0 06:47:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:47: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')]} 06:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47: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')]} 06:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47: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', '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 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 06:47: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 mozsystemmonitor==0.0.0 06:47:12 INFO - Using env: {'DISPLAY': ':0', 06:47:12 INFO - 'HOME': '/home/cltbld', 06:47:12 INFO - 'LANG': 'en_US.UTF-8', 06:47:12 INFO - 'LANGUAGE': 'en_US:en', 06:47:12 INFO - 'LOGNAME': 'cltbld', 06:47:12 INFO - 'MAIL': '/var/mail/cltbld', 06:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:12 INFO - 'MOZ_NO_REMOTE': '1', 06:47:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:12 INFO - 'NO_EM_RESTART': '1', 06:47:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:12 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:12 INFO - 'SHELL': '/bin/bash', 06:47:12 INFO - 'SHLVL': '1', 06:47:12 INFO - 'TERM': 'linux', 06:47:12 INFO - 'TMOUT': '86400', 06:47:12 INFO - 'USER': 'cltbld', 06:47:12 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:12 INFO - '_': '/tools/buildbot/bin/python'} 06:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:47:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 06:47:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:47:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:47:14 INFO - Installing collected packages: mozsystemmonitor 06:47:14 INFO - Running setup.py install for mozsystemmonitor 06:47:14 INFO - Successfully installed mozsystemmonitor 06:47:14 INFO - Cleaning up... 06:47:15 INFO - Return code: 0 06:47:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:47: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')]} 06:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47: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')]} 06:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 06:47:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:47:15 INFO - Using env: {'DISPLAY': ':0', 06:47:15 INFO - 'HOME': '/home/cltbld', 06:47:15 INFO - 'LANG': 'en_US.UTF-8', 06:47:15 INFO - 'LANGUAGE': 'en_US:en', 06:47:15 INFO - 'LOGNAME': 'cltbld', 06:47:15 INFO - 'MAIL': '/var/mail/cltbld', 06:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:15 INFO - 'MOZ_NO_REMOTE': '1', 06:47:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:15 INFO - 'NO_EM_RESTART': '1', 06:47:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:15 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:15 INFO - 'SHELL': '/bin/bash', 06:47:15 INFO - 'SHLVL': '1', 06:47:15 INFO - 'TERM': 'linux', 06:47:15 INFO - 'TMOUT': '86400', 06:47:15 INFO - 'USER': 'cltbld', 06:47:15 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:15 INFO - '_': '/tools/buildbot/bin/python'} 06:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:15 INFO - Downloading/unpacking blobuploader==1.2.4 06:47: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 06:47: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 06:47: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 06:47: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 06:47: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 06:47: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 06:47:16 INFO - Downloading blobuploader-1.2.4.tar.gz 06:47:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 06:47:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:47:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:47:16 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 06:47:16 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 06:47:16 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 06:47:16 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 06:47:16 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 06:47:16 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 06:47:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 06:47:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:47:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:47: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 06:47: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 06:47: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 06:47: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 06:47: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 06:47: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 06:47:17 INFO - Downloading docopt-0.6.1.tar.gz 06:47:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 06:47:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:47:17 INFO - Installing collected packages: blobuploader, requests, docopt 06:47:17 INFO - Running setup.py install for blobuploader 06:47:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:47:17 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:47:17 INFO - Running setup.py install for requests 06:47:18 INFO - Running setup.py install for docopt 06:47:18 INFO - Successfully installed blobuploader requests docopt 06:47:18 INFO - Cleaning up... 06:47:18 INFO - Return code: 0 06:47:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 06:47:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:47:18 INFO - Using env: {'DISPLAY': ':0', 06:47:18 INFO - 'HOME': '/home/cltbld', 06:47:18 INFO - 'LANG': 'en_US.UTF-8', 06:47:18 INFO - 'LANGUAGE': 'en_US:en', 06:47:18 INFO - 'LOGNAME': 'cltbld', 06:47:18 INFO - 'MAIL': '/var/mail/cltbld', 06:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:18 INFO - 'MOZ_NO_REMOTE': '1', 06:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:18 INFO - 'NO_EM_RESTART': '1', 06:47:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:18 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:18 INFO - 'SHELL': '/bin/bash', 06:47:18 INFO - 'SHLVL': '1', 06:47:18 INFO - 'TERM': 'linux', 06:47:18 INFO - 'TMOUT': '86400', 06:47:18 INFO - 'USER': 'cltbld', 06:47:18 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:18 INFO - '_': '/tools/buildbot/bin/python'} 06:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:47:18 INFO - Running setup.py (path:/tmp/pip-oLIEpd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:47:18 INFO - Running setup.py (path:/tmp/pip-UXvi1Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:47:18 INFO - Running setup.py (path:/tmp/pip-ceuEP9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:47:18 INFO - Running setup.py (path:/tmp/pip-4AQ721-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:47:18 INFO - Running setup.py (path:/tmp/pip-gzK_n7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:47:18 INFO - Running setup.py (path:/tmp/pip-jklykz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:47:18 INFO - Running setup.py (path:/tmp/pip-Ua32f2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:47:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:47:18 INFO - Running setup.py (path:/tmp/pip-0mR282-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:47:19 INFO - Running setup.py (path:/tmp/pip-y2MLWm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:47:19 INFO - Running setup.py (path:/tmp/pip-PMFv1Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:47:19 INFO - Running setup.py (path:/tmp/pip-kzyaWA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:47:19 INFO - Running setup.py (path:/tmp/pip-cBA_RS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:47:19 INFO - Running setup.py (path:/tmp/pip-tBdA3v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:47:19 INFO - Running setup.py (path:/tmp/pip-QtM49X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:47:19 INFO - Running setup.py (path:/tmp/pip-TDi4o2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:47:19 INFO - Running setup.py (path:/tmp/pip-_NWtqX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:47:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:47:19 INFO - Running setup.py (path:/tmp/pip-AJVke_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:47:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:47:19 INFO - Running setup.py install for manifestparser 06:47:19 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:47:19 INFO - Running setup.py install for mozcrash 06:47:19 INFO - Running setup.py install for mozdebug 06:47:19 INFO - Running setup.py install for mozdevice 06:47:20 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Running setup.py install for mozfile 06:47:20 INFO - Running setup.py install for mozhttpd 06:47:20 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Running setup.py install for mozinfo 06:47:20 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Running setup.py install for mozInstall 06:47:20 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Running setup.py install for mozleak 06:47:20 INFO - Running setup.py install for mozlog 06:47:20 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Running setup.py install for moznetwork 06:47:20 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:47:20 INFO - Running setup.py install for mozprocess 06:47:20 INFO - Running setup.py install for mozprofile 06:47:21 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:47:21 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:47:21 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:47:21 INFO - Running setup.py install for mozrunner 06:47:21 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:47:21 INFO - Running setup.py install for mozscreenshot 06:47:21 INFO - Running setup.py install for moztest 06:47:21 INFO - Running setup.py install for mozversion 06:47:21 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:47:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:47:21 INFO - Cleaning up... 06:47:21 INFO - Return code: 0 06:47:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:47:21 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')]} 06:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:21 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')]} 06:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:21 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 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47:21 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 06:47:21 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 06:47:21 INFO - Using env: {'DISPLAY': ':0', 06:47:21 INFO - 'HOME': '/home/cltbld', 06:47:21 INFO - 'LANG': 'en_US.UTF-8', 06:47:21 INFO - 'LANGUAGE': 'en_US:en', 06:47:21 INFO - 'LOGNAME': 'cltbld', 06:47:21 INFO - 'MAIL': '/var/mail/cltbld', 06:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:21 INFO - 'MOZ_NO_REMOTE': '1', 06:47:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:21 INFO - 'NO_EM_RESTART': '1', 06:47:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:21 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:21 INFO - 'SHELL': '/bin/bash', 06:47:21 INFO - 'SHLVL': '1', 06:47:21 INFO - 'TERM': 'linux', 06:47:21 INFO - 'TMOUT': '86400', 06:47:21 INFO - 'USER': 'cltbld', 06:47:21 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:21 INFO - '_': '/tools/buildbot/bin/python'} 06:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:47:21 INFO - Running setup.py (path:/tmp/pip-mGv8gA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:47:21 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)) 06:47:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:47:21 INFO - Running setup.py (path:/tmp/pip-80qSWA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:47:21 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)) 06:47:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:47:21 INFO - Running setup.py (path:/tmp/pip-VknY_r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:47:21 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)) 06:47:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:47:21 INFO - Running setup.py (path:/tmp/pip-LBDie3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:47:21 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)) 06:47:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:47:21 INFO - Running setup.py (path:/tmp/pip-9VaJG1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:47:22 INFO - Running setup.py (path:/tmp/pip-OXxKNs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:47:22 INFO - Running setup.py (path:/tmp/pip-9NiNNf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:47:22 INFO - Running setup.py (path:/tmp/pip-PITl0j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:47:22 INFO - Running setup.py (path:/tmp/pip-SMPbyF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:47:22 INFO - Running setup.py (path:/tmp/pip-ddrYkR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:47:22 INFO - Running setup.py (path:/tmp/pip-nOrSPG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:47:22 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:47:22 INFO - Running setup.py (path:/tmp/pip-Q6BK5q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:47:22 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:47:22 INFO - Running setup.py (path:/tmp/pip-BQTxjH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:47:22 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:47:22 INFO - Running setup.py (path:/tmp/pip-gywEbF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:47:22 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:47:22 INFO - Running setup.py (path:/tmp/pip-2J28pm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:47:22 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:47:22 INFO - Running setup.py (path:/tmp/pip-CoBwWI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:47:22 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)) 06:47:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:47:22 INFO - Running setup.py (path:/tmp/pip-XkIkDx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:47:22 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)) 06:47:22 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)) 06:47:22 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)) 06:47:22 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)) 06:47:22 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)) 06:47:22 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)) 06:47:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:47:22 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 06:47:22 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 06:47:22 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 06:47:22 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 06:47:22 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 06:47:22 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 06:47:24 INFO - Downloading blessings-1.5.1.tar.gz 06:47:24 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 06:47:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:47:24 INFO - Installing collected packages: blessings 06:47:24 INFO - Running setup.py install for blessings 06:47:24 INFO - Successfully installed blessings 06:47:24 INFO - Cleaning up... 06:47:24 INFO - Return code: 0 06:47:24 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:47:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:47:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:47:25 INFO - Reading from file tmpfile_stdout 06:47:25 INFO - Current package versions: 06:47:25 INFO - argparse == 1.2.1 06:47:25 INFO - blessings == 1.5.1 06:47:25 INFO - blobuploader == 1.2.4 06:47:25 INFO - docopt == 0.6.1 06:47:25 INFO - manifestparser == 1.1 06:47:25 INFO - mozInstall == 1.12 06:47:25 INFO - mozcrash == 0.16 06:47:25 INFO - mozdebug == 0.1 06:47:25 INFO - mozdevice == 0.47 06:47:25 INFO - mozfile == 1.2 06:47:25 INFO - mozhttpd == 0.7 06:47:25 INFO - mozinfo == 0.9 06:47:25 INFO - mozleak == 0.1 06:47:25 INFO - mozlog == 3.1 06:47:25 INFO - moznetwork == 0.27 06:47:25 INFO - mozprocess == 0.22 06:47:25 INFO - mozprofile == 0.27 06:47:25 INFO - mozrunner == 6.11 06:47:25 INFO - mozscreenshot == 0.1 06:47:25 INFO - mozsystemmonitor == 0.0 06:47:25 INFO - moztest == 0.7 06:47:25 INFO - mozversion == 1.4 06:47:25 INFO - psutil == 3.1.1 06:47:25 INFO - requests == 1.2.3 06:47:25 INFO - wsgiref == 0.1.2 06:47:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa764932c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x282a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', '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 06:47:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 06:47:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:47:25 INFO - Using env: {'DISPLAY': ':0', 06:47:25 INFO - 'HOME': '/home/cltbld', 06:47:25 INFO - 'LANG': 'en_US.UTF-8', 06:47:25 INFO - 'LANGUAGE': 'en_US:en', 06:47:25 INFO - 'LOGNAME': 'cltbld', 06:47:25 INFO - 'MAIL': '/var/mail/cltbld', 06:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:25 INFO - 'MOZ_NO_REMOTE': '1', 06:47:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:25 INFO - 'NO_EM_RESTART': '1', 06:47:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:25 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:25 INFO - 'SHELL': '/bin/bash', 06:47:25 INFO - 'SHLVL': '1', 06:47:25 INFO - 'TERM': 'linux', 06:47:25 INFO - 'TMOUT': '86400', 06:47:25 INFO - 'USER': 'cltbld', 06:47:25 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:25 INFO - '_': '/tools/buildbot/bin/python'} 06:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 06:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:47:25 INFO - Cleaning up... 06:47:25 INFO - Return code: 0 06:47:25 INFO - Running post-action listener: _resource_record_post_action 06:47:25 INFO - Running post-action listener: _start_resource_monitoring 06:47:25 INFO - Starting resource monitoring. 06:47:25 INFO - ##### 06:47:25 INFO - ##### Running install step. 06:47:25 INFO - ##### 06:47:25 INFO - Running pre-action listener: _resource_record_pre_action 06:47:25 INFO - Running main action method: install 06:47:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:47:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:47:25 INFO - Reading from file tmpfile_stdout 06:47:25 INFO - Detecting whether we're running mozinstall >=1.0... 06:47:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:47:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:47:25 INFO - Reading from file tmpfile_stdout 06:47:25 INFO - Output received: 06:47:25 INFO - Usage: mozinstall [options] installer 06:47:25 INFO - Options: 06:47:25 INFO - -h, --help show this help message and exit 06:47:25 INFO - -d DEST, --destination=DEST 06:47:25 INFO - Directory to install application into. [default: 06:47:25 INFO - "/builds/slave/test-pgo"] 06:47:25 INFO - --app=APP Application being installed. [default: firefox] 06:47:25 INFO - mkdir: /builds/slave/test-pgo/build/application 06:47:25 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'] 06:47:25 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 06:47:40 INFO - Reading from file tmpfile_stdout 06:47:40 INFO - Output received: 06:47:40 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:47:40 INFO - Running post-action listener: _resource_record_post_action 06:47:40 INFO - ##### 06:47:40 INFO - ##### Running run-tests step. 06:47:40 INFO - ##### 06:47:40 INFO - Running pre-action listener: _resource_record_pre_action 06:47:40 INFO - Running main action method: run_tests 06:47:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:47:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:47:40 INFO - Python 2.7.3 06:47:40 INFO - Return code: 0 06:47:40 INFO - grabbing minidump binary from tooltool 06:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2920d70>, '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 0x2921b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:47:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 06:47:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:47:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:47:40 INFO - Return code: 0 06:47:40 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:47:40 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:47:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:47:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:47:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:47:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 06:47:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --webServer localhost 06:47:40 INFO - Using env: {'DISPLAY': ':0', 06:47:40 INFO - 'HOME': '/home/cltbld', 06:47:40 INFO - 'LANG': 'en_US.UTF-8', 06:47:40 INFO - 'LANGUAGE': 'en_US:en', 06:47:40 INFO - 'LOGNAME': 'cltbld', 06:47:40 INFO - 'MAIL': '/var/mail/cltbld', 06:47:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:47:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:40 INFO - 'MOZ_NO_REMOTE': '1', 06:47:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:47:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:40 INFO - 'NO_EM_RESTART': '1', 06:47:40 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:40 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:40 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:47:40 INFO - 'SHELL': '/bin/bash', 06:47:40 INFO - 'SHLVL': '1', 06:47:40 INFO - 'TERM': 'linux', 06:47:40 INFO - 'TMOUT': '86400', 06:47:40 INFO - 'USER': 'cltbld', 06:47:40 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460', 06:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:40 INFO - '_': '/tools/buildbot/bin/python'} 06:47:40 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] with output_timeout 3600 06:47:40 INFO - mozversion INFO | application_buildid: 20151123043004 06:47:40 INFO - mozversion INFO | application_changeset: b9b6a1567ef6b27735e19b661e895753db58e902 06:47:40 INFO - mozversion INFO | application_display_name: Nightly 06:47:40 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:47:40 INFO - mozversion INFO | application_name: Firefox 06:47:40 INFO - mozversion INFO | application_remotingname: firefox 06:47:40 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:47:40 INFO - mozversion INFO | application_vendor: Mozilla 06:47:40 INFO - mozversion INFO | application_version: 45.0a1 06:47:40 INFO - mozversion INFO | platform_buildid: 20151123043004 06:47:40 INFO - mozversion INFO | platform_changeset: b9b6a1567ef6b27735e19b661e895753db58e902 06:47:40 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:47:40 INFO - mozversion INFO | platform_version: 45.0a1 06:47:40 INFO - 2015-11-23 06:47:40,590 DEBUG : using testdate: 1448290060 06:47:40 INFO - 2015-11-23 06:47:40,590 DEBUG : actual date: 1448290060 06:47:40 INFO - 2015-11-23 06:47:40,604 INFO : starting webserver on 'localhost:37367' 06:47:40 INFO - 2015-11-23 06:47:40,605 INFO : Starting test suite talos-linux64-ix-068.e 06:47:40 INFO - 2015-11-23 06:47:40,605 INFO : Starting test dromaeo_css 06:47:40 INFO - 2015-11-23 06:47:40,605 DEBUG : operating with platform_type : linux_ 06:47:40 INFO - 2015-11-23 06:47:40,606 INFO : Initialising browser for dromaeo_css test... 06:47:40 INFO - 2015-11-23 06:47:40,622 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCf44Xa/profile http://localhost:37367/getInfo.html 06:47:45 INFO - 2015-11-23 06:47:45,318 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:47:45 INFO - 2015-11-23 06:47:45,318 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:47:45 INFO - 2015-11-23 06:47:45,318 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:47:45 INFO - 2015-11-23 06:47:45,318 DEBUG : BROWSER_OUTPUT: __metrics 06:47:46 INFO - 2015-11-23 06:47:46,412 INFO : Browser initialized. 06:47:46 INFO - 2015-11-23 06:47:46,412 INFO : Running cycle 1/1 for dromaeo_css test... 06:47:46 INFO - 2015-11-23 06:47:46,413 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCf44Xa/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:47:58 INFO - 2015-11-23 06:47:58,215 DEBUG : BROWSER_OUTPUT: RSS: Main: 141901824 06:47:58 INFO - 2015-11-23 06:47:58,216 DEBUG : BROWSER_OUTPUT: 06:48:47 INFO - 2015-11-23 06:48:47,839 DEBUG : BROWSER_OUTPUT: 1448290127838 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] 06:50:56 INFO - 2015-11-23 06:50:56,946 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37367/tests/dromaeo/cssquery-ext.html) 06:50:57 INFO - 2015-11-23 06:50:57,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 147795968 06:50:57 INFO - 2015-11-23 06:50:57,991 DEBUG : BROWSER_OUTPUT: 06:54:00 INFO - 2015-11-23 06:54:00,110 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/dromaeo/cssquery-ext.html (next: http://localhost:37367/tests/dromaeo/cssquery-jquery.html) 06:54:00 INFO - 2015-11-23 06:54:00,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 142299136 06:54:00 INFO - 2015-11-23 06:54:00,696 DEBUG : BROWSER_OUTPUT: 06:57:02 INFO - 2015-11-23 06:57:02,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37367/tests/dromaeo/cssquery-mootools.html) 06:57:03 INFO - 2015-11-23 06:57:03,018 DEBUG : BROWSER_OUTPUT: RSS: Main: 142520320 06:57:03 INFO - 2015-11-23 06:57:03,018 DEBUG : BROWSER_OUTPUT: 07:00:04 INFO - 2015-11-23 07:00:04,682 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37367/tests/dromaeo/cssquery-prototype.html) 07:00:05 INFO - 2015-11-23 07:00:05,275 DEBUG : BROWSER_OUTPUT: RSS: Main: 141672448 07:00:05 INFO - 2015-11-23 07:00:05,276 DEBUG : BROWSER_OUTPUT: 07:03:07 INFO - 2015-11-23 07:03:07,597 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37367/tests/dromaeo/cssquery-yui.html) 07:03:08 INFO - 2015-11-23 07:03:08,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 142753792 07:03:08 INFO - 2015-11-23 07:03:08,220 DEBUG : BROWSER_OUTPUT: 07:06:10 INFO - 2015-11-23 07:06:10,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/dromaeo/cssquery-yui.html (next: http://localhost:37367/tests/dromaeo/cssquery-dojo.html) 07:06:11 INFO - 2015-11-23 07:06:11,054 DEBUG : BROWSER_OUTPUT: RSS: Main: 144322560 07:06:11 INFO - 2015-11-23 07:06:11,055 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,056 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:06:11 INFO - 2015-11-23 07:06:11,056 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:06:11 INFO - 2015-11-23 07:06:11,056 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:06:11 INFO - 2015-11-23 07:06:11,056 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:06:11 INFO - 2015-11-23 07:06:11,058 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3231.353729254149;3275.0502354317236;3309.040378290296;3327.550780644612;3330.833458327083;5340.198970154479;5431.103867861798;5473.288472498574;5474.069408200606;5490.890182196364;5438.809641662542;5463.426340234278;5510.201020851984;5553.944460555394;5555.972220138904;4951.084049450854;5005.2492126181;5054.039732450841;5077.289179514871;5090.083784918713;5001.424836143836;5060.569851562622;5088.694678319294;5114.386273647167;5145.74271286436;4903.607711383081;4963.2058870580895;5030.0694371541385;5038.420581633086;5076.010178015288;5093.898122037575;5095.3376061112185;5103.719295438749;5119.436861945183;5122.052420302256;5068.822591209327;5073.568746656539;5084.593232541388;5103.719295438749;5128.179491281415;5134.127198376248;5232.555232805178;5291.656042357235;5415.729213539307;5426.891462170774;6023.156758053876;6023.909641355383;6027;6027.698615069229;6036.094585812163;6048.213732214828;6050.092486127116;6052.364501727299;6065.969670151665;6069.180660610805;5445.591580631469;5472.781088756445;5502.80740174092;5551.8889622207735;5557.360903496109;6141.785037523665;6154.5076393888385;6158.291796443376;6163.907541386883;6168.814935551941;5729.083346664514;5921.67430791302;5922.822315330547;5933.110033495011;5943.673097979653;5785.871755007733;5891.351951285355;5966.403359664086;5972.970135149297;6001.159837622737;5898.705064746746;5925.496332811759;5927.73325200367;5961.254843144607;5981.312149102821;5897.675627840425;5943.138244954457;5952.642841429441;5963.970180149158;5966.761329546856;4455.1980643484485;4473.530279320625;4499.235130027903;4539.5460453955;4559.840405585855;4093.1199792044854;4097.97951010249;4113.91772164555;4131.8967025824595;4171.749695018309;5527.364353099365;5785.10330898703;5787.276590426197;5804.593678442553;5827.35899051104;5877.794277200277;5931.703414829241;5956.463918247292;5956.880862382728;5995.970020149872;5704.828855134353;5724.026915424388;5732.283464566929;5736.426357364314;5767.740451679684;5028.823991160292;5209.71346575942;5231.29377534028;5238.68567885928;5240.580753539708;6078.088286757022;6132.7546898124465;6136.969315153485;6140.232470941132;6142.498775697899;5939.10913362999;5944.8811023780145;5958.463738263577;5961.701914904324;5962.016267316018;6110.406879924546;6119.510439164945;6128.380904898417;6159.846003849761;6166.938330616636;5761.366249712444;5776;5790.94209057904;5805.158252053513;5883.705814709162;5940.851478713067;5959.28488581373;5966.0454327307425;5967.582269241111;5974.731137098928;5645.379008309164;5701.144828275792;5747.74135163927;5784.913226301524;5840.357560668238;5850.912236316373;5886.175935368966;5888.234529511136;5892.410758924073;5893.911591326219;5525.060739674184;5728.140778883034;5751.884962300815;5758.942410576008;5777.480026797609;5643.266375371176;5794.391588883106;5796.76812927495;5811;5851.853703657443;5516.255305533701;5592.524635406036;5638.60529762929;5644.096944488862;5806.2451881255165 07:06:11 INFO - 2015-11-23 07:06:11,059 DEBUG : BROWSER_OUTPUT: |1;ext.html;584.9268841394826;586.0301201511497;587.5534593708782;602.6083046020084;635.8728254349131;40800.183996320055;42069.57930420695;42075.36886946698;42108.157836843246;42258.57741422589;42388.364174537404;42986.57013429872;43064;43112.784436077774;43143.568564314344;8614.827703445928;8688.261497772697;8718.651253949834;8798.736037918872;8807.559622018905;8575.442596231256;8636.870446943269;8648.827023459527;8667.523286219255;8686.522241276727;8509.064002959669;8578.313734901225;8591.914080859175;8599.355048371366;8599.527026013568;8350.039745429292;8405;8416.957915210416;8419.326453883674;8463.788405289855;8476.237138657489;8582.270507006911;8582.699605513839;8590.69932552364;8636.654533818639;38847.41728874069;39398.80300598507;39448.802755986326;39461.802690986646;39539.60460395388;3374.0383990562573;3514.947275790865;3662.468942003421;3837.175007373428;4117.670586353084;30163;30461.400627146395;31342.529862052088;31540.36919261625;31573;3677.5219221501034;3727.9813600932093;3769.151940813295;3834.865779697725;3912.2371137628174;276462;278038;279570;280480;280794;42540.787296063325;43013.569864301586;43183.35224971628;57867.42132578621;58404.12393814096;42685.35971960423;42718.78640606839;42858.785706071896;42872.356914646305;42918.57081429146;14734.557963261119;14776.926115369355;14866.776998345034;14899.925500372428;14914.552563423114;11847.940760296317;11850.644480665595;11869.16028015659;11923.70190745221;11960.76078478443;14306.856931430553;14398.568042958519;14402.495912643008;14426.278686065654;14497.420103195966;5949.762009519571;6033;6056.303525094582;6056.757729690763;6121.632702037892;963.4860677724741;976.1946394224793;977.8093271811929;998.0568362897138;1008.4554340655964;28779.42441151207;28785.424291514053;28839.134825955254;29133.708662913523;29405;1035.9378437293788;1060.3584831177027;1067.5356220044305;1080.3463904337762;1117.7149826794116;15212.619684507976;15228.923855380652;15275.770863437057;17098.487045388658;17680.90584147769;379881;381428;382708;382788;385205;56600.433995659514;56682;56786.7160664194;57059;57178.42821571731;1331.227887825059;1341.4298922957703;1369.452219112343;1378.421063153478;1390.8748212660707;29217;30426.239344016576;31764.841175793972;33166.17084572905;33236.335273294884;51021.744891276794;51955.480445196576;52149.7392513035;52232.73883630557;52237;284091;286502;288656;289884;290037;43272.783636081615;43591;44073.77963110164;44425.333619996374;44581.55418445774;44112.558874410846;45292;45345;45619.543804562854;46122.76938615285;37230.8138459306;37334.43998339973;38261;38415.23169536538;38865.41701874418;38623.613763862;40037.39943900902;41605.37591936184;42832.57167428286;43030.78484607557;40942;41103.1779364417;41600.167996640506;41966;42114.57885421229;31299.37401252008;31607;34088.48867266943;34178.14554636154;34851.65148348484;32779.836100819346;34516.30967380588;34755.30489390148;34761.304773904885;34803 07:06:11 INFO - 2015-11-23 07:06:11,063 DEBUG : BROWSER_OUTPUT: |2;jquery.html;361.0738455860717;361.45420415173083;361.95113659655937;362.87662194853743;364.6098674418372;16611.169441527934;17114.400995965145;17812.10939453027;17815.64368712628;18321.175547100378;11264.605738799144;13024.348782560857;16967.83032169678;17367.392141275057;17385.304587816474;4856.344393506884;5022.82420115296;5036.924446133311;5049.368828896388;5055.747212639371;5038.773255203525;5066.949330506703;5088.974555127238;5094.1594636884865;5108.565771909392;3672.9081772955756;3714;5258.47415258473;5367.114426119686;5421.484958928895;5403.729813509329;5424.1321388577835;5450.373207081197;5456.672599644034;5468.644538105031;5020.748962551857;5196.532312091892;5202.531772140525;5262.815801446931;5270.841874743764;12794.42425090873;13393.799093013613;15838.683226335408;21485.785142148536;21542.246021389175;2025.2405347994502;2026.807453291935;2048.047657839097;2073.5541858500496;2074.6369385357502;9332.300077494216;9494.098060684293;9781;11662.650120496399;12069.456874440672;1622.9288669478206;1657.4447560067392;1770.3184274054581;1773.9556511087326;1789.498940296719;62436;62709.37290627127;62713.37286627121;64053;64920.67539662271;10272.794544109076;10505.68482945513;10509.054185123223;10548.103411209979;10580.735481612868;13659.726805463835;13690.794638080437;13734.793978090336;13742.725145497034;13775.38010789517;7642.121156067124;7682.308592226726;7691.384689224848;7751.651175697107;7974.60126993648;7190.7483238086415;7216.8917466238045;7221.53060051095;7229;7415.295546923034;4395.1868904252815;4404.405405270248;4429.867103986886;4500.639948804088;4525.909481810411;1861.26480040386;1922.807719228094;1927.4121392974996;1951.9024048797503;1957.2464601128383;443.74706417341804;451.93672885796013;463.1894185175942;859.3726579596929;1043.8068957242933;11073.944630276957;15325.540233793005;16677.332906683598;16850.325986960383;17625.38311159129;930.7719608695913;937.1659223291276;1000.8548760429696;1029.7322696098918;1044.3264094658903;9970.800583988279;10135.746606334753;10212.642557510451;10251.7437064074;10283.640072597573;500981;510973;533952;550237;551551;84313.66685605528;85623.67970275585;85928.28143437096;86912.13087869166;88247;1186.7329850783676;1187.5368606243571;1190.1966172833304;1191.2316555821446;1197.3773637708343;15693.686126277638;15895.52313430599;16039.438619648256;16117;16272.43046493367;33967.66032339645;34365;37081.258374831814;37108.81445592863;37817.432738509495;43164;44824.55175448204;48513.27230091622;48663;49592.00815983732;9595.376300540442;9881.209503239867;9986.550605222928;10015.59937602517;10152.847707284249;18212.81787182164;18386.377960961923;18432.907835460737;18495.167717452478;18605.162767675756;13866.306684665928;15013.849861501245;15059.6988060236;15257.6185595361;15671.843281567495;14160.929195354369;14332.426702931765;14413;14676.853231467549;14806.555803325917;14670.413183472541;14916.254187290375;14956.850431495546;15050.397984080515;15162.620934476727;9634.084761947885;9865.852012219966;9946.204303655835;10074.496275186359;10113.797724045626;10402.791944160923;10455.215858810772;14575.781363279348;15016.399344026117;15369.846301536842 07:06:11 INFO - 2015-11-23 07:06:11,064 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1460.8612181842725;1507.4045751927988;1600.6638605892763;1637.860781833544;1744.1540852686444;7384.298491643292;7648.235176482349;7655.885161722578;7746.4190185736015;8018.198180181979;7735.7679269622;7743.070831500214;7819.374450043997;7845.725399611015;7846.960765196166;2243.2372993182275;2282.3267136194863;2284.8514846535004;2294.3346429535413;2302.804261637763;2041.9591608167827;2077.356019633918;2097.4966008158085;2108.1988844239168;2110.198124712607;2226.92205772798;2280.407094155515;2288.0275882749847;2295.908163673451;2301.0680674326923;2227.276135256032;2234.3743751749535;2285.839991200625;2296.8392212545127;2303.1593468384003;2294.598445272071;2325.8837058147024;2327.650852372141;2329.208069256444;2337.812974961999;7669.348105411047;7768.184340644264;7772.533647981083;7780.299773020399;7792.103908050533;4690.530946905316;4770.0936822003705;4789.784459699287;4806.975965120152;4808.278758186265;3072.4469595472806;3086.3055812442244;3126.812391256532;3148.5277208418693;3162.0513845846153;957.8802649668792;959.3332633819498;960.8750862387915;961.6874515782399;993.4883534979491;10125.038121378531;10142.594296228142;10185.388876667424;10245.743856403575;10272.537735801907;4106.897327566835;4108.198901214294;4160.271952408317;4181.247375472383;4270.231358355463;8502.489850608983;8509.808626792254;8516.40385173032;8525.95737021311;8532.104129066483;4695.3426520287185;4707.929381059287;4714.5992590630185;4723.48041715411;4729.881752956135;4330.978345108318;4332.1119170569955;4337.91552111972;4340.392345071693;4344.869653910325;3413.010227034132;3418.6239513653977;3418.9145271368416;3433.9313213736086;3440.7935523868646;1089.54239219528;1102.818035024228;1103.4703342395535;1106.9335839849473;1110.5724296146043;956.8660387545749;957.4542510768795;1048.538642997094;1138.6242539961959;1193.1349771415655;7250.854982900312;7318.451116166309;7338.633068346561;7342.926570734331;7399.704011839466;1143.4702484142017;1149.350616901448;1155.5089087137933;1187.1986409173774;1193.3496244546743;5762.164486149569;5791.507721843606;5806.21616081832;5810.360860305363;5830.329512106162;9093.863592046124;9201.861972070425;9226.35415520907;9344.205742511827;9382.48396338208;9246.676366327147;9288.489133097744;9399.201067909304;9457.574409151604;9473.431594104239;7145.464090193256;7146.2496437874315;7193.748218812421;7339;7425.1461081975185;7288.67200975968;7313.414926805736;7317.12194536659;7340.70637174507;7349.30181632744;8074;8299.087100418828;8319.958400207961;8473.237408633006;8483.236508714246;8419.8737018946;8426;8526.78683032929;8568.057513673615;8722.040575536812;6399.168108145911;6832.93167068323;6930.7227710891;6960.651967401711;6979.1973922999505;7379.225181356095;7385.29839665231;7446.180920098893;7447.478676492594;7491.400687945061;6821.386075253251;6867.793966181022;6942.444604431533;6951.687174077279;7126.322999315057;6800.217974932748;6812.591244525344;6850.177978642595;6953.23514413404;7004.649767511706;7030.156381234285;7060.505764596429;7079.858402832017;7083.82290442743;7113.893291600527;6583.736650534117;6593.736250550116;6614.6361950093205;6628.337166283333;6636.203655561363;6382.585131966407;6428.839279017875;6435.646039467874;6449.322821103717;6595.901061484177 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: |4;prototype.html;338.0686209492847;346.6775898414475;347.8591170575917;348.39379479705286;352.381570344046;6197.628142311464;6252.531060170489;6283.120363149163;6288.245410550729;6358.109864618957;6090;6185.938140618592;6186.56694031418;6233.438990490867;6264.248290205171;4794.808207671689;4845.885446347343;4870.65905386623;4878.170710979124;4880.341153944208;4882.584980276681;4886.071646387193;4891.315215869781;4891.5353041461185;4896.877578060541;4742.691725037878;4847.927281090787;4849.709017458946;4862.659613827033;4868.707877527359;4819.397575303087;4826.565609095163;4828.203346447867;4828.903421931559;4830.589399901012;4226.028013556868;4228.873133805991;4242.809073591696;4253.128108737701;4266.957330426718;5886.175935369051;5903.49820265278;5908.14331921869;5928.318243402021;5934.198883150762;5450.427705090988;5632.718364081779;5644.096944488903;5658.773649054074;5667.971660141714;6715.832104197385;6716.227633822075;6722.697478613474;6725.798226053226;6736.292689267654;4709.62323014158;4714.151452738506;4720.952790472085;4725.31482934976;4737.052589482118;6855.794326170223;6908.101946746941;6910.965445172742;6955.860882782316;6978.197507286676;4801.68789028712;4815.831445899376;4817.445993710698;4817.964137710339;4821.566059054702;4811.422629284508;4873.512648735169;4874.95125048745;4875.414950205998;4884.291777692215;5489.725513724298;5541.445855414427;5570.832875013675;5582.33012038558;5585.106382978785;6632.668366581652;6698.8995165072565;6732.528722989442;6741.561798483181;6749.460043196533;1687.0889719551305;1723.3623559283146;1738.6261953679766;1739.2781995471737;1743.102302314309;1174.1780753472603;1183.0949323767325;1196.0312147160828;1196.0969468051564;1208.3414539076225;6457.935420645827;6499.1876015498065;6501;6507.121538592324;6516;6787.1176747023555;6828.146481689789;6834.453243740489;6839.726410943606;6846.383825455733;6316.589421687576;6439.581427207217;6449.580777249464;6455.4835613150835;6478.319776423502;3744.7378683491893;3783.829727662262;3791.7914514701956;3792.5259342582176;3792.962070379316;7311.89032164518;7313.085864266966;7321.670524826395;7358.558486490828;7367.26327367259;7020.122484689414;7156.248593897566;7179.210286868441;7182.928170718331;7199.460040496983;7300.9634951825965;7309;7314.08573928259;7316.158641756159;7334.853302933911;6902.585844849124;6906.965465172642;6953.269906659728;6957.512974091765;6975.267596902252;6747.527673062838;6871.9656401717675;6877.4841886857685;6900.275471075464;6900.6204658744255;7268.0914885639295;7276.345128938421;7289.8177545559665;7306.7077316906725;7331.450141239322;4668.509806470408;4711;4711.78796954131;4713.057388522378;4720.45714742809;6691.1636045494315;6764.120664313607;6907.378335949788;6940.375366217065;6983.1620205575655;6735.764248251287;6749.460043196631;6754.493412994143;6764.5603035802515;6784.728610855511;6728.529002969745;6730.125083739083;6731.966340168267;6732.528722989344;6780.423663988616;6523.380278873499;6613.6693165340575;6632.403083722488;6638.20341559016;6646.5347425681675;6696.4977626679165;6791.286914873867;6791.93208067913;6795.864082718417;6800.625965571743;6555.573887697285;6568.7372505099265;6593.670316484253;6610;6635.037919501742 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: |5;yui.html;769.4536878816041;812.8740045292978;820.723774530605;831.6423937706786;857.2916925061396;13723.451061957523;14230.359633816477;14250.28748562573;14314.713705725879;14316.71366572668;13583.185008899472;13714.657133571665;14386.424543018293;14901.180435076067;15060.472883449082;810.2181394953868;829.1832544943226;829.6515463505342;830.900291964965;840.0423517190408;832.633641197874;836.5022811427191;840.7351684219482;843.3253397282181;844.1853611265128;878.4509681449094;890.1543533643065;890.9509976951265;892.576026387464;892.6117139044536;882.3602887906248;884.9734507964707;885.1148851148852;886.6231851463135;888.7467071884514;648.993510064898;649.5161104976822;651.2217899609968;652.103357882911;654.8722999015193;14238.501652442115;14244.715105697931;14356.282185890665;14367.856321436757;14369.856301436956;10678.946605267001;11977.880221197764;12344.95067919219;12543.808338207855;12906.225626462443;10461.790764184674;10516.263861529696;10562.894371056267;10680.679579612624;10860.945695271474;3657.487951686766;3670.2475992421755;3706.4069748840325;3739.738218324718;3852.8651497197466;49546;49659;49779.50220497821;49779.75110124463;50221.74889125531;10109.34289271195;10481.42352170638;10524.31591946521;10541.262111652264;10898.945505272422;18876.716849247274;18969.051547422572;19005.95467249287;19060.142293596822;19096.80903190978;7582;7775.92224077752;7961.482503637245;7977.401694872908;7984.520928744294;4350.782460877007;4369.541198174209;4389.100234451994;4420.668449866273;4628.60656844172;3154.873805047818;3166.825824579624;3168.12876458971;3171.20719820045;3171.698688624577;593.6556797057697;596.6509591888794;609.0681257675764;612.3967891626744;614.532954954229;556.7800718716165;614.3273115938041;646.873859597374;684.4968947823346;700.4046560423701;8546;8765.517896515752;8931.151540603632;8968.730938071869;9840.901590984142;537.9004884096455;661.4609093588806;697.5500801982693;697.5540828308142;698.8847583643122;3841.5966323536186;3960.148568057881;4020.8592699255387;4214.7471151731;4246.235677578004;54297;55794;55966.72016639891;56618.716906415204;57827.421725783046;50760;51028.74485627548;51356;51356.48643513592;52155.21767173496;47715.76142119267;48382.27426588604;48388;48389.5161048385;49274;8946.507942063248;9169.266458683156;9210.493422861808;9314.673986410444;9360.11078947499;35864;36621.633783661826;37027;37574.62425375821;38687.41968870525;42663.5733642672;42820.78589607032;45833.54166458426;45918.770406147756;47018.29472557983;11176.720581985515;11388.202825802446;11395.487203076047;11438.82841757391;11443.771124577295;12254.38728063611;12608.306543140216;12755.681107972376;12796.168249063781;12925.15986460877;11485.483153257956;11911.285322880654;11932.880671193177;12262.632121036384;12299.323537205182;10884.945575272073;11402.657920262405;11515.078793696652;11790.351530665896;11892.643220703392;10671.679849604525;11707.88292117068;11798.52805887755;12167.634970950885;12178.6955326114;9794;9799.951000244953;10077.053390895608;10153;10300.639477618248;8900.910990890266;9036.502992335221;9601.903980960102;9794.510274486105;10058.34620749649 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,300 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: __startTimestamp1448291171055__endTimestamp 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,068 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:06:11 INFO - 2015-11-23 07:06:11,069 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:06:11 INFO - 2015-11-23 07:06:11,069 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,069 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5523.66 Median:5758.94 stddev:620.79 (10.8%) stddev-sans-first:596.08 07:06:11 INFO - 2015-11-23 07:06:11,070 DEBUG : BROWSER_OUTPUT: Values: 3231.4 3275.1 3309.0 3327.6 3330.8 5340.2 5431.1 5473.3 5474.1 5490.9 5438.8 5463.4 5510.2 5553.9 5556.0 4951.1 5005.2 5054.0 5077.3 5090.1 5001.4 5060.6 5088.7 5114.4 5145.7 4903.6 4963.2 5030.1 5038.4 5076.0 5093.9 5095.3 5103.7 5119.4 5122.1 5068.8 5073.6 5084.6 5103.7 5128.2 5134.1 5232.6 5291.7 5415.7 5426.9 6023.2 6023.9 6027.0 6027.7 6036.1 6048.2 6050.1 6052.4 6066.0 6069.2 5445.6 5472.8 5502.8 5551.9 5557.4 6141.8 6154.5 6158.3 6163.9 6168.8 5729.1 5921.7 5922.8 5933.1 5943.7 5785.9 5891.4 5966.4 5973.0 6001.2 5898.7 5925.5 5927.7 5961.3 5981.3 5897.7 5943.1 5952.6 5964.0 5966.8 4455.2 4473.5 4499.2 4539.5 4559.8 4093.1 4098.0 4113.9 4131.9 4171.7 5527.4 5785.1 5787.3 5804.6 5827.4 5877.8 5931.7 5956.5 5956.9 5996.0 5704.8 5724.0 5732.3 5736.4 5767.7 5028.8 5209.7 5231.3 5238.7 5240.6 6078.1 6132.8 6137.0 6140.2 6142.5 5939.1 5944.9 5958.5 5961.7 5962.0 6110.4 6119.5 6128.4 6159.8 6166.9 5761.4 5776.0 5790.9 5805.2 5883.7 5940.9 5959.3 5966.0 5967.6 5974.7 5645.4 5701.1 5747.7 5784.9 5840.4 5850.9 5886.2 5888.2 5892.4 5893.9 5525.1 5728.1 5751.9 5758.9 5777.5 5643.3 5794.4 5796.8 5811.0 5851.9 5516.3 5592.5 5638.6 5644.1 5806.2 07:06:11 INFO - 2015-11-23 07:06:11,070 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,070 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48914.58 Median:39788.50 stddev:83801.45 (210.6%) stddev-sans-first:83957.97 07:06:11 INFO - 2015-11-23 07:06:11,070 DEBUG : BROWSER_OUTPUT: Values: 584.9 586.0 587.6 602.6 635.9 40800.2 42069.6 42075.4 42108.2 42258.6 42388.4 42986.6 43064.0 43112.8 43143.6 8614.8 8688.3 8718.7 8798.7 8807.6 8575.4 8636.9 8648.8 8667.5 8686.5 8509.1 8578.3 8591.9 8599.4 8599.5 8350.0 8405.0 8417.0 8419.3 8463.8 8476.2 8582.3 8582.7 8590.7 8636.7 38847.4 39398.8 39448.8 39461.8 39539.6 3374.0 3514.9 3662.5 3837.2 4117.7 30163.0 30461.4 31342.5 31540.4 31573.0 3677.5 3728.0 3769.2 3834.9 3912.2 276462.0 278038.0 279570.0 280480.0 280794.0 42540.8 43013.6 43183.4 57867.4 58404.1 42685.4 42718.8 42858.8 42872.4 42918.6 14734.6 14776.9 14866.8 14899.9 14914.6 11847.9 11850.6 11869.2 11923.7 11960.8 14306.9 14398.6 14402.5 14426.3 14497.4 5949.8 6033.0 6056.3 6056.8 6121.6 963.5 976.2 977.8 998.1 1008.5 28779.4 28785.4 28839.1 29133.7 29405.0 1035.9 1060.4 1067.5 1080.3 1117.7 15212.6 15228.9 15275.8 17098.5 17680.9 379881.0 381428.0 382708.0 382788.0 385205.0 56600.4 56682.0 56786.7 57059.0 57178.4 1331.2 1341.4 1369.5 1378.4 1390.9 29217.0 30426.2 31764.8 33166.2 33236.3 51021.7 51955.5 52149.7 52232.7 52237.0 284091.0 286502.0 288656.0 289884.0 290037.0 43272.8 43591.0 44073.8 44425.3 44581.6 44112.6 45292.0 45345.0 45619.5 46122.8 37230.8 37334.4 38261.0 38415.2 38865.4 38623.6 40037.4 41605.4 42832.6 43030.8 40942.0 41103.2 41600.2 41966.0 42114.6 31299.4 31607.0 34088.5 34178.1 34851.7 32779.8 34516.3 34755.3 34761.3 34803.0 07:06:11 INFO - 2015-11-23 07:06:11,070 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,071 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28828.55 Median:2074.10 stddev:86745.56 (4182.3%) stddev-sans-first:86962.57 07:06:11 INFO - 2015-11-23 07:06:11,071 DEBUG : BROWSER_OUTPUT: Values: 361.1 361.5 362.0 362.9 364.6 16611.2 17114.4 17812.1 17815.6 18321.2 11264.6 13024.3 16967.8 17367.4 17385.3 4856.3 5022.8 5036.9 5049.4 5055.7 5038.8 5066.9 5089.0 5094.2 5108.6 3672.9 3714.0 5258.5 5367.1 5421.5 5403.7 5424.1 5450.4 5456.7 5468.6 5020.7 5196.5 5202.5 5262.8 5270.8 12794.4 13393.8 15838.7 21485.8 21542.2 2025.2 2026.8 2048.0 2073.6 2074.6 9332.3 9494.1 9781.0 11662.7 12069.5 1622.9 1657.4 1770.3 1774.0 1789.5 62436.0 62709.4 62713.4 64053.0 64920.7 10272.8 10505.7 10509.1 10548.1 10580.7 13659.7 13690.8 13734.8 13742.7 13775.4 7642.1 7682.3 7691.4 7751.7 7974.6 7190.7 7216.9 7221.5 7229.0 7415.3 4395.2 4404.4 4429.9 4500.6 4525.9 1861.3 1922.8 1927.4 1951.9 1957.2 443.7 451.9 463.2 859.4 1043.8 11073.9 15325.5 16677.3 16850.3 17625.4 930.8 937.2 1000.9 1029.7 1044.3 9970.8 10135.7 10212.6 10251.7 10283.6 500981.0 510973.0 533952.0 550237.0 551551.0 84313.7 85623.7 85928.3 86912.1 88247.0 1186.7 1187.5 1190.2 1191.2 1197.4 15693.7 15895.5 16039.4 16117.0 16272.4 33967.7 34365.0 37081.3 37108.8 37817.4 43164.0 44824.6 48513.3 48663.0 49592.0 9595.4 9881.2 9986.6 10015.6 10152.8 18212.8 18386.4 18432.9 18495.2 18605.2 13866.3 15013.8 15059.7 15257.6 15671.8 14160.9 14332.4 14413.0 14676.9 14806.6 14670.4 14916.3 14956.9 15050.4 15162.6 9634.1 9865.9 9946.2 10074.5 10113.8 10402.8 10455.2 14575.8 15016.4 15369.8 07:06:11 INFO - 2015-11-23 07:06:11,071 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,071 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5417.28 Median:6605.27 stddev:2783.22 (42.1%) stddev-sans-first:2775.14 07:06:11 INFO - 2015-11-23 07:06:11,072 DEBUG : BROWSER_OUTPUT: Values: 1460.9 1507.4 1600.7 1637.9 1744.2 7384.3 7648.2 7655.9 7746.4 8018.2 7735.8 7743.1 7819.4 7845.7 7847.0 2243.2 2282.3 2284.9 2294.3 2302.8 2042.0 2077.4 2097.5 2108.2 2110.2 2226.9 2280.4 2288.0 2295.9 2301.1 2227.3 2234.4 2285.8 2296.8 2303.2 2294.6 2325.9 2327.7 2329.2 2337.8 7669.3 7768.2 7772.5 7780.3 7792.1 4690.5 4770.1 4789.8 4807.0 4808.3 3072.4 3086.3 3126.8 3148.5 3162.1 957.9 959.3 960.9 961.7 993.5 10125.0 10142.6 10185.4 10245.7 10272.5 4106.9 4108.2 4160.3 4181.2 4270.2 8502.5 8509.8 8516.4 8526.0 8532.1 4695.3 4707.9 4714.6 4723.5 4729.9 4331.0 4332.1 4337.9 4340.4 4344.9 3413.0 3418.6 3418.9 3433.9 3440.8 1089.5 1102.8 1103.5 1106.9 1110.6 956.9 957.5 1048.5 1138.6 1193.1 7250.9 7318.5 7338.6 7342.9 7399.7 1143.5 1149.4 1155.5 1187.2 1193.3 5762.2 5791.5 5806.2 5810.4 5830.3 9093.9 9201.9 9226.4 9344.2 9382.5 9246.7 9288.5 9399.2 9457.6 9473.4 7145.5 7146.2 7193.7 7339.0 7425.1 7288.7 7313.4 7317.1 7340.7 7349.3 8074.0 8299.1 8320.0 8473.2 8483.2 8419.9 8426.0 8526.8 8568.1 8722.0 6399.2 6832.9 6930.7 6960.7 6979.2 7379.2 7385.3 7446.2 7447.5 7491.4 6821.4 6867.8 6942.4 6951.7 7126.3 6800.2 6812.6 6850.2 6953.2 7004.6 7030.2 7060.5 7079.9 7083.8 7113.9 6583.7 6593.7 6614.6 6628.3 6636.2 6382.6 6428.8 6435.6 6449.3 6595.9 07:06:11 INFO - 2015-11-23 07:06:11,072 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,072 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5629.89 Median:6398.85 stddev:1692.38 (26.4%) stddev-sans-first:1649.86 07:06:11 INFO - 2015-11-23 07:06:11,072 DEBUG : BROWSER_OUTPUT: Values: 338.1 346.7 347.9 348.4 352.4 6197.6 6252.5 6283.1 6288.2 6358.1 6090.0 6185.9 6186.6 6233.4 6264.2 4794.8 4845.9 4870.7 4878.2 4880.3 4882.6 4886.1 4891.3 4891.5 4896.9 4742.7 4847.9 4849.7 4862.7 4868.7 4819.4 4826.6 4828.2 4828.9 4830.6 4226.0 4228.9 4242.8 4253.1 4267.0 5886.2 5903.5 5908.1 5928.3 5934.2 5450.4 5632.7 5644.1 5658.8 5668.0 6715.8 6716.2 6722.7 6725.8 6736.3 4709.6 4714.2 4721.0 4725.3 4737.1 6855.8 6908.1 6911.0 6955.9 6978.2 4801.7 4815.8 4817.4 4818.0 4821.6 4811.4 4873.5 4875.0 4875.4 4884.3 5489.7 5541.4 5570.8 5582.3 5585.1 6632.7 6698.9 6732.5 6741.6 6749.5 1687.1 1723.4 1738.6 1739.3 1743.1 1174.2 1183.1 1196.0 1196.1 1208.3 6457.9 6499.2 6501.0 6507.1 6516.0 6787.1 6828.1 6834.5 6839.7 6846.4 6316.6 6439.6 6449.6 6455.5 6478.3 3744.7 3783.8 3791.8 3792.5 3793.0 7311.9 7313.1 7321.7 7358.6 7367.3 7020.1 7156.2 7179.2 7182.9 7199.5 7301.0 7309.0 7314.1 7316.2 7334.9 6902.6 6907.0 6953.3 6957.5 6975.3 6747.5 6872.0 6877.5 6900.3 6900.6 7268.1 7276.3 7289.8 7306.7 7331.5 4668.5 4711.0 4711.8 4713.1 4720.5 6691.2 6764.1 6907.4 6940.4 6983.2 6735.8 6749.5 6754.5 6764.6 6784.7 6728.5 6730.1 6732.0 6732.5 6780.4 6523.4 6613.7 6632.4 6638.2 6646.5 6696.5 6791.3 6791.9 6795.9 6800.6 6555.6 6568.7 6593.7 6610.0 6635.0 07:06:11 INFO - 2015-11-23 07:06:11,072 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,073 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14157.79 Median:45876.16 stddev:16133.69 (35.2%) stddev-sans-first:16147.62 07:06:11 INFO - 2015-11-23 07:06:11,074 DEBUG : BROWSER_OUTPUT: Values: 769.5 812.9 820.7 831.6 857.3 13723.5 14230.4 14250.3 14314.7 14316.7 13583.2 13714.7 14386.4 14901.2 15060.5 810.2 829.2 829.7 830.9 840.0 832.6 836.5 840.7 843.3 844.2 878.5 890.2 891.0 892.6 892.6 882.4 885.0 885.1 886.6 888.7 649.0 649.5 651.2 652.1 654.9 14238.5 14244.7 14356.3 14367.9 14369.9 10678.9 11977.9 12345.0 12543.8 12906.2 10461.8 10516.3 10562.9 10680.7 10860.9 3657.5 3670.2 3706.4 3739.7 3852.9 49546.0 49659.0 49779.5 49779.8 50221.7 10109.3 10481.4 10524.3 10541.3 10898.9 18876.7 18969.1 19006.0 19060.1 19096.8 7582.0 7775.9 7961.5 7977.4 7984.5 4350.8 4369.5 4389.1 4420.7 4628.6 3154.9 3166.8 3168.1 3171.2 3171.7 593.7 596.7 609.1 612.4 614.5 556.8 614.3 646.9 684.5 700.4 8546.0 8765.5 8931.2 8968.7 9840.9 537.9 661.5 697.6 697.6 698.9 3841.6 3960.1 4020.9 4214.7 4246.2 54297.0 55794.0 55966.7 56618.7 57827.4 50760.0 51028.7 51356.0 51356.5 52155.2 47715.8 48382.3 48388.0 48389.5 49274.0 8946.5 9169.3 9210.5 9314.7 9360.1 35864.0 36621.6 37027.0 37574.6 38687.4 42663.6 42820.8 45833.5 45918.8 47018.3 11176.7 11388.2 11395.5 11438.8 11443.8 12254.4 12608.3 12755.7 12796.2 12925.2 11485.5 11911.3 11932.9 12262.6 12299.3 10884.9 11402.7 11515.1 11790.4 11892.6 10671.7 11707.9 11798.5 12167.6 12178.7 9794.0 9800.0 10077.1 10153.0 10300.6 8900.9 9036.5 9601.9 9794.5 10058.3 07:06:11 INFO - 2015-11-23 07:06:11,074 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:06:11 INFO - 2015-11-23 07:06:11,074 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2015-11-23 07:06:11,776 INFO : Browser exited with error code: 0 07:06:11 INFO - 2015-11-23 07:06:11,795 INFO : Completed test dromaeo_css (00:18:31) 07:06:11 INFO - 2015-11-23 07:06:11,795 INFO : Starting test kraken 07:06:11 INFO - 2015-11-23 07:06:11,795 DEBUG : operating with platform_type : linux_ 07:06:11 INFO - 2015-11-23 07:06:11,795 INFO : Initialising browser for kraken test... 07:06:11 INFO - 2015-11-23 07:06:11,801 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMVuzqn/profile http://localhost:37367/getInfo.html 07:06:16 INFO - 2015-11-23 07:06:16,221 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:06:16 INFO - 2015-11-23 07:06:16,221 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:06:16 INFO - 2015-11-23 07:06:16,221 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:06:16 INFO - 2015-11-23 07:06:16,221 DEBUG : BROWSER_OUTPUT: __metrics 07:06:17 INFO - 2015-11-23 07:06:17,204 INFO : Browser initialized. 07:06:17 INFO - 2015-11-23 07:06:17,204 INFO : Running cycle 1/1 for kraken test... 07:06:17 INFO - 2015-11-23 07:06:17,205 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMVuzqn/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:06:28 INFO - 2015-11-23 07:06:28,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 141905920 07:06:28 INFO - 2015-11-23 07:06:28,987 DEBUG : BROWSER_OUTPUT: 07:06:31 INFO - 2015-11-23 07:06:31,467 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37367/tests/kraken/driver.html?testName=audio-beat-detection) 07:06:32 INFO - 2015-11-23 07:06:32,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 142839808 07:06:32 INFO - 2015-11-23 07:06:32,080 DEBUG : BROWSER_OUTPUT: 07:06:34 INFO - 2015-11-23 07:06:34,718 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37367/tests/kraken/driver.html?testName=audio-dft) 07:06:35 INFO - 2015-11-23 07:06:35,298 DEBUG : BROWSER_OUTPUT: RSS: Main: 142811136 07:06:35 INFO - 2015-11-23 07:06:35,299 DEBUG : BROWSER_OUTPUT: 07:06:38 INFO - 2015-11-23 07:06:38,163 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37367/tests/kraken/driver.html?testName=audio-fft) 07:06:38 INFO - 2015-11-23 07:06:38,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 137977856 07:06:38 INFO - 2015-11-23 07:06:38,744 DEBUG : BROWSER_OUTPUT: 07:06:40 INFO - 2015-11-23 07:06:40,749 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37367/tests/kraken/driver.html?testName=audio-oscillator) 07:06:41 INFO - 2015-11-23 07:06:41,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 136396800 07:06:41 INFO - 2015-11-23 07:06:41,322 DEBUG : BROWSER_OUTPUT: 07:06:43 INFO - 2015-11-23 07:06:43,541 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:06:44 INFO - 2015-11-23 07:06:44,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 138338304 07:06:44 INFO - 2015-11-23 07:06:44,122 DEBUG : BROWSER_OUTPUT: 07:06:47 INFO - 2015-11-23 07:06:47,102 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37367/tests/kraken/driver.html?testName=imaging-darkroom) 07:06:47 INFO - 2015-11-23 07:06:47,683 DEBUG : BROWSER_OUTPUT: RSS: Main: 138670080 07:06:47 INFO - 2015-11-23 07:06:47,684 DEBUG : BROWSER_OUTPUT: 07:06:51 INFO - 2015-11-23 07:06:51,092 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37367/tests/kraken/driver.html?testName=imaging-desaturate) 07:06:51 INFO - 2015-11-23 07:06:51,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 139137024 07:06:51 INFO - 2015-11-23 07:06:51,678 DEBUG : BROWSER_OUTPUT: 07:06:54 INFO - 2015-11-23 07:06:54,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37367/tests/kraken/driver.html?testName=json-parse-financial) 07:06:55 INFO - 2015-11-23 07:06:55,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 139489280 07:06:55 INFO - 2015-11-23 07:06:55,119 DEBUG : BROWSER_OUTPUT: 07:06:56 INFO - 2015-11-23 07:06:56,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:06:57 INFO - 2015-11-23 07:06:57,301 DEBUG : BROWSER_OUTPUT: RSS: Main: 139968512 07:06:57 INFO - 2015-11-23 07:06:57,301 DEBUG : BROWSER_OUTPUT: 07:06:59 INFO - 2015-11-23 07:06:59,124 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:06:59 INFO - 2015-11-23 07:06:59,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 139247616 07:06:59 INFO - 2015-11-23 07:06:59,734 DEBUG : BROWSER_OUTPUT: 07:07:01 INFO - 2015-11-23 07:07:01,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:07:02 INFO - 2015-11-23 07:07:02,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 140857344 07:07:02 INFO - 2015-11-23 07:07:02,412 DEBUG : BROWSER_OUTPUT: 07:07:04 INFO - 2015-11-23 07:07:04,860 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:07:05 INFO - 2015-11-23 07:07:05,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 140943360 07:07:05 INFO - 2015-11-23 07:07:05,462 DEBUG : BROWSER_OUTPUT: 07:07:08 INFO - 2015-11-23 07:07:08,058 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:07:08 INFO - 2015-11-23 07:07:08,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 140255232 07:07:08 INFO - 2015-11-23 07:07:08,648 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,362 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37367/tests/kraken/driver.html?testName=ai-astar) 07:07:10 INFO - 2015-11-23 07:07:10,612 DEBUG : BROWSER_OUTPUT: RSS: Main: 140263424 07:07:10 INFO - 2015-11-23 07:07:10,612 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,613 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:07:10 INFO - 2015-11-23 07:07:10,613 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:07:10 INFO - 2015-11-23 07:07:10,614 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:07:10 INFO - 2015-11-23 07:07:10,614 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:07:10 INFO - 2015-11-23 07:07:10,614 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;105;111;111;110;123;111;110;109;109 07:07:10 INFO - 2015-11-23 07:07:10,615 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;124;151;178;126;150;157;132;168;127 07:07:10 INFO - 2015-11-23 07:07:10,615 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;162;163;163;161;163;162;172;163 07:07:10 INFO - 2015-11-23 07:07:10,615 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;76;73;72;74;140;72;79;75;72 07:07:10 INFO - 2015-11-23 07:07:10,615 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;135;92;92;94;138;93;93;134 07:07:10 INFO - 2015-11-23 07:07:10,615 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;111;111;112;111;111;110;110;110 07:07:10 INFO - 2015-11-23 07:07:10,616 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;146;146;145;149;146;145;147;146;146 07:07:10 INFO - 2015-11-23 07:07:10,616 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;97;96;98;97;96;96;96 07:07:10 INFO - 2015-11-23 07:07:10,616 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;66;67;64;64;68;65;67;64;63;62 07:07:10 INFO - 2015-11-23 07:07:10,616 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;68;69;70;70;68;70;69;69 07:07:10 INFO - 2015-11-23 07:07:10,616 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;91;85;85;92;83;86;84;86;85 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;127;120;116;117;124;117;121;117;130 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;150;151;163;152;150;147;148;153;140 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;67;67;70;69;66;68;67;67;66 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,714 07:07:10 INFO - 2015-11-23 07:07:10,617 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:07:10 INFO - 2015-11-23 07:07:10,618 DEBUG : BROWSER_OUTPUT: __startTimestamp1448291230613__endTimestamp 07:07:10 INFO - 2015-11-23 07:07:10,618 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,621 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:07:10 INFO - 2015-11-23 07:07:10,621 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:07:10 INFO - 2015-11-23 07:07:10,621 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,622 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:4.61 (4.1%) stddev-sans-first:4.87 07:07:10 INFO - 2015-11-23 07:07:10,622 DEBUG : BROWSER_OUTPUT: Values: 112.0 105.0 111.0 111.0 110.0 123.0 111.0 110.0 109.0 109.0 07:07:10 INFO - 2015-11-23 07:07:10,622 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,622 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.30 Median:150.50 stddev:18.64 (12.4%) stddev-sans-first:19.72 07:07:10 INFO - 2015-11-23 07:07:10,622 DEBUG : BROWSER_OUTPUT: Values: 150.0 124.0 151.0 178.0 126.0 150.0 157.0 132.0 168.0 127.0 07:07:10 INFO - 2015-11-23 07:07:10,622 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,623 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:3.19 (2.0%) stddev-sans-first:3.28 07:07:10 INFO - 2015-11-23 07:07:10,623 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 162.0 163.0 163.0 161.0 163.0 162.0 172.0 163.0 07:07:10 INFO - 2015-11-23 07:07:10,623 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,623 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.50 Median:73.50 stddev:21.03 (28.6%) stddev-sans-first:22.08 07:07:10 INFO - 2015-11-23 07:07:10,623 DEBUG : BROWSER_OUTPUT: Values: 72.0 76.0 73.0 72.0 74.0 140.0 72.0 79.0 75.0 72.0 07:07:10 INFO - 2015-11-23 07:07:10,623 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:93.00 stddev:20.64 (22.2%) stddev-sans-first:21.45 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 135.0 92.0 92.0 94.0 138.0 93.0 93.0 134.0 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.60 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 111.0 111.0 112.0 111.0 111.0 110.0 110.0 110.0 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,624 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:1.26 (0.9%) stddev-sans-first:1.20 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: Values: 148.0 146.0 146.0 145.0 149.0 146.0 145.0 147.0 146.0 146.0 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 97.0 96.0 98.0 97.0 96.0 96.0 96.0 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.50 stddev:1.94 (3.0%) stddev-sans-first:2.03 07:07:10 INFO - 2015-11-23 07:07:10,625 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 64.0 64.0 68.0 65.0 67.0 64.0 63.0 62.0 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 68.0 69.0 70.0 70.0 68.0 70.0 69.0 69.0 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.50 stddev:2.94 (3.4%) stddev-sans-first:3.08 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: Values: 85.0 91.0 85.0 85.0 92.0 83.0 86.0 84.0 86.0 85.0 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:122.50 stddev:4.81 (3.9%) stddev-sans-first:5.00 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: Values: 124.0 127.0 120.0 116.0 117.0 124.0 117.0 121.0 117.0 130.0 07:07:10 INFO - 2015-11-23 07:07:10,626 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,627 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.50 Median:150.50 stddev:6.45 (4.3%) stddev-sans-first:6.06 07:07:10 INFO - 2015-11-23 07:07:10,627 DEBUG : BROWSER_OUTPUT: Values: 141.0 150.0 151.0 163.0 152.0 150.0 147.0 148.0 153.0 140.0 07:07:10 INFO - 2015-11-23 07:07:10,627 DEBUG : BROWSER_OUTPUT: 07:07:10 INFO - 2015-11-23 07:07:10,627 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:67.50 stddev:2.16 (3.2%) stddev-sans-first:1.33 07:07:10 INFO - 2015-11-23 07:07:10,627 DEBUG : BROWSER_OUTPUT: Values: 73.0 67.0 67.0 70.0 69.0 66.0 68.0 67.0 67.0 66.0 07:07:10 INFO - 2015-11-23 07:07:10,628 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:07:10 INFO - 2015-11-23 07:07:10,628 DEBUG : BROWSER_OUTPUT: 07:07:11 INFO - 2015-11-23 07:07:11,644 INFO : Browser exited with error code: 0 07:07:11 INFO - 2015-11-23 07:07:11,695 INFO : Completed test kraken (00:00:59) 07:07:11 INFO - 2015-11-23 07:07:11,695 INFO : Starting test v8_7 07:07:11 INFO - 2015-11-23 07:07:11,695 DEBUG : operating with platform_type : linux_ 07:07:11 INFO - 2015-11-23 07:07:11,695 INFO : Initialising browser for v8_7 test... 07:07:11 INFO - 2015-11-23 07:07:11,701 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPEKUCO/profile http://localhost:37367/getInfo.html 07:07:16 INFO - 2015-11-23 07:07:16,061 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:07:16 INFO - 2015-11-23 07:07:16,061 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:07:16 INFO - 2015-11-23 07:07:16,061 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:07:16 INFO - 2015-11-23 07:07:16,061 DEBUG : BROWSER_OUTPUT: __metrics 07:07:17 INFO - 2015-11-23 07:07:17,032 INFO : Browser initialized. 07:07:17 INFO - 2015-11-23 07:07:17,032 INFO : Running cycle 1/1 for v8_7 test... 07:07:17 INFO - 2015-11-23 07:07:17,033 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPEKUCO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:07:28 INFO - 2015-11-23 07:07:28,804 DEBUG : BROWSER_OUTPUT: RSS: Main: 142106624 07:07:28 INFO - 2015-11-23 07:07:28,804 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,178 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37367/tests/v8_7/run.html (next: http://localhost:37367/tests/v8_7/run.html) 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 139517952 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:07:50 INFO - 2015-11-23 07:07:50,429 DEBUG : BROWSER_OUTPUT: |0;Richards;184.1959845275373 07:07:50 INFO - 2015-11-23 07:07:50,430 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.34468166955185 07:07:50 INFO - 2015-11-23 07:07:50,430 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.4060999717594 07:07:50 INFO - 2015-11-23 07:07:50,430 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5060.606060606061 07:07:50 INFO - 2015-11-23 07:07:50,430 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1050.420168067227 07:07:50 INFO - 2015-11-23 07:07:50,430 DEBUG : BROWSER_OUTPUT: |5;Earley;700.9803921568628 07:07:50 INFO - 2015-11-23 07:07:50,430 DEBUG : BROWSER_OUTPUT: |6;Boyer;12228.915662650603 07:07:50 INFO - 2015-11-23 07:07:50,431 DEBUG : BROWSER_OUTPUT: |7;RegExp;27459.45945945946 07:07:50 INFO - 2015-11-23 07:07:50,431 DEBUG : BROWSER_OUTPUT: |8;Splay;642.2607578676943 07:07:50 INFO - 2015-11-23 07:07:50,431 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 07:07:50 INFO - 2015-11-23 07:07:50,431 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:07:50 INFO - 2015-11-23 07:07:50,432 DEBUG : BROWSER_OUTPUT: __startTimestamp1448291270428__endTimestamp 07:07:50 INFO - 2015-11-23 07:07:50,432 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,432 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:07:50 INFO - 2015-11-23 07:07:50,432 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:07:50 INFO - 2015-11-23 07:07:50,432 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,433 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:184.20 Median:184.20 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,433 DEBUG : BROWSER_OUTPUT: Values: 184.2 07:07:50 INFO - 2015-11-23 07:07:50,433 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,433 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.34 Median:192.34 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,433 DEBUG : BROWSER_OUTPUT: Values: 192.3 07:07:50 INFO - 2015-11-23 07:07:50,433 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,434 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.41 Median:282.41 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,434 DEBUG : BROWSER_OUTPUT: Values: 282.4 07:07:50 INFO - 2015-11-23 07:07:50,434 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,434 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5060.61 Median:5060.61 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,434 DEBUG : BROWSER_OUTPUT: Values: 5060.6 07:07:50 INFO - 2015-11-23 07:07:50,434 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,435 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1050.42 Median:1050.42 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,435 DEBUG : BROWSER_OUTPUT: Values: 1050.4 07:07:50 INFO - 2015-11-23 07:07:50,435 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,435 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:700.98 Median:700.98 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,436 DEBUG : BROWSER_OUTPUT: Values: 701.0 07:07:50 INFO - 2015-11-23 07:07:50,436 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,436 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12228.92 Median:12228.92 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,436 DEBUG : BROWSER_OUTPUT: Values: 12228.9 07:07:50 INFO - 2015-11-23 07:07:50,436 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27459.46 Median:27459.46 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: Values: 27459.5 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:642.26 Median:642.26 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: Values: 642.3 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: 07:07:50 INFO - 2015-11-23 07:07:50,438 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 07:07:50 INFO - 2015-11-23 07:07:50,439 DEBUG : BROWSER_OUTPUT: Values: 4850.2 07:07:50 INFO - 2015-11-23 07:07:50,439 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:07:50 INFO - 2015-11-23 07:07:50,439 DEBUG : BROWSER_OUTPUT: 07:07:51 INFO - 2015-11-23 07:07:51,264 INFO : Browser exited with error code: 0 07:07:51 INFO - 2015-11-23 07:07:51,281 INFO : Completed test v8_7 (00:00:39) 07:07:51 INFO - 2015-11-23 07:07:51,453 INFO : Completed test suite (00:20:10) 07:07:51 INFO - 2015-11-23 07:07:51,453 DEBUG : Working with test: dromaeo_css 07:07:51 INFO - 2015-11-23 07:07:51,453 DEBUG : Generating results file: dromaeo_css 07:07:51 INFO - 2015-11-23 07:07:51,454 DEBUG : Working with test: kraken 07:07:51 INFO - 2015-11-23 07:07:51,455 DEBUG : Generating results file: kraken 07:07:51 INFO - 2015-11-23 07:07:51,455 INFO : javascript benchmark 07:07:51 INFO - 2015-11-23 07:07:51,455 DEBUG : Working with test: v8_7 07:07:51 INFO - 2015-11-23 07:07:51,455 DEBUG : Generating results file: v8_7 07:07:51 INFO - 2015-11-23 07:07:51,455 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:07:51 INFO - 2015-11-23 07:07:51,514 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 07:07:51 INFO - 2015-11-23 07:07:51,514 DEBUG : process_Request line: dromaeo_css 7152.50 graph.html#tests=[[72,63,43]] 07:07:51 INFO - 2015-11-23 07:07:51,514 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:07:51 INFO - 2015-11-23 07:07:51,555 DEBUG : process_Request line: kraken 1519.40 graph.html#tests=[[232,63,43]] 07:07:51 INFO - 2015-11-23 07:07:51,555 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:07:51 INFO - 2015-11-23 07:07:51,584 DEBUG : process_Request line: v8_7 20914.06 graph.html#tests=[[230,63,43]] 07:07:51 INFO - 2015-11-23 07:07:51,585 INFO : javascript benchmark 07:07:51 INFO - 2015-11-23 07:07:51,589 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3231.353729254149, 3275.0502354317236, 3309.040378290296, 3327.550780644612, 3330.833458327083, 5340.198970154479, 5431.103867861798, 5473.288472498574, 5474.069408200606, 5490.890182196364, 5438.809641662542, 5463.426340234278, 5510.201020851984, 5553.944460555394, 5555.972220138904, 4951.084049450854, 5005.2492126181, 5054.039732450841, 5077.289179514871, 5090.083784918713, 5001.424836143836, 5060.569851562622, 5088.694678319294, 5114.386273647167, 5145.74271286436, 4903.607711383081, 4963.2058870580895, 5030.0694371541385, 5038.420581633086, 5076.010178015288, 5093.898122037575, 5095.3376061112185, 5103.719295438749, 5119.436861945183, 5122.052420302256, 5068.822591209327, 5073.568746656539, 5084.593232541388, 5103.719295438749, 5128.179491281415, 5134.127198376248, 5232.555232805178, 5291.656042357235, 5415.729213539307, 5426.891462170774, 6023.156758053876, 6023.909641355383, 6027.0, 6027.698615069229, 6036.094585812163, 6048.213732214828, 6050.092486127116, 6052.364501727299, 6065.969670151665, 6069.180660610805, 5445.591580631469, 5472.781088756445, 5502.80740174092, 5551.8889622207735, 5557.360903496109, 6141.785037523665, 6154.5076393888385, 6158.291796443376, 6163.907541386883, 6168.814935551941, 5729.083346664514, 5921.67430791302, 5922.822315330547, 5933.110033495011, 5943.673097979653, 5785.871755007733, 5891.351951285355, 5966.403359664086, 5972.970135149297, 6001.159837622737, 5898.705064746746, 5925.496332811759, 5927.73325200367, 5961.254843144607, 5981.312149102821, 5897.675627840425, 5943.138244954457, 5952.642841429441, 5963.970180149158, 5966.761329546856, 4455.1980643484485, 4473.530279320625, 4499.235130027903, 4539.5460453955, 4559.840405585855, 4093.1199792044854, 4097.97951010249, 4113.91772164555, 4131.8967025824595, 4171.749695018309, 5527.364353099365, 5785.10330898703, 5787.276590426197, 5804.593678442553, 5827.35899051104, 5877.794277200277, 5931.703414829241, 5956.463918247292, 5956.880862382728, 5995.970020149872, 5704.828855134353, 5724.026915424388, 5732.283464566929, 5736.426357364314, 5767.740451679684, 5028.823991160292, 5209.71346575942, 5231.29377534028, 5238.68567885928, 5240.580753539708, 6078.088286757022, 6132.7546898124465, 6136.969315153485, 6140.232470941132, 6142.498775697899, 5939.10913362999, 5944.8811023780145, 5958.463738263577, 5961.701914904324, 5962.016267316018, 6110.406879924546, 6119.510439164945, 6128.380904898417, 6159.846003849761, 6166.938330616636, 5761.366249712444, 5776.0, 5790.94209057904, 5805.158252053513, 5883.705814709162, 5940.851478713067, 5959.28488581373, 5966.0454327307425, 5967.582269241111, 5974.731137098928, 5645.379008309164, 5701.144828275792, 5747.74135163927, 5784.913226301524, 5840.357560668238, 5850.912236316373, 5886.175935368966, 5888.234529511136, 5892.410758924073, 5893.911591326219, 5525.060739674184, 5728.140778883034, 5751.884962300815, 5758.942410576008, 5777.480026797609, 5643.266375371176, 5794.391588883106, 5796.76812927495, 5811.0, 5851.853703657443, 5516.255305533701, 5592.524635406036, 5638.60529762929, 5644.096944488862, 5806.2451881255165], "unit": "ms", "name": "dojo.html", "value": 5482.867577763419}, {"lowerIsBetter": false, "replicates": [584.9268841394826, 586.0301201511497, 587.5534593708782, 602.6083046020084, 635.8728254349131, 40800.183996320055, 42069.57930420695, 42075.36886946698, 42108.157836843246, 42258.57741422589, 42388.364174537404, 42986.57013429872, 43064.0, 43112.784436077774, 43143.568564314344, 8614.827703445928, 8688.261497772697, 8718.651253949834, 8798.736037918872, 8807.559622018905, 8575.442596231256, 8636.870446943269, 8648.827023459527, 8667.523286219255, 8686.522241276727, 8509.064002959669, 8578.313734901225, 8591.914080859175, 8599.355048371366, 8599.527026013568, 8350.039745429292, 8405.0, 8416.957915210416, 8419.326453883674, 8463.788405289855, 8476.237138657489, 8582.270507006911, 8582.699605513839, 8590.69932552364, 8636.654533818639, 38847.41728874069, 39398.80300598507, 39448.802755986326, 39461.802690986646, 39539.60460395388, 3374.0383990562573, 3514.947275790865, 3662.468942003421, 3837.175007373428, 4117.670586353084, 30163.0, 30461.400627146395, 31342.529862052088, 31540.36919261625, 31573.0, 3677.5219221501034, 3727.9813600932093, 3769.151940813295, 3834.865779697725, 3912.2371137628174, 276462.0, 278038.0, 279570.0, 280480.0, 280794.0, 42540.787296063325, 43013.569864301586, 43183.35224971628, 57867.42132578621, 58404.12393814096, 42685.35971960423, 42718.78640606839, 42858.785706071896, 42872.356914646305, 42918.57081429146, 14734.557963261119, 14776.926115369355, 14866.776998345034, 14899.925500372428, 14914.552563423114, 11847.940760296317, 11850.644480665595, 11869.16028015659, 11923.70190745221, 11960.76078478443, 14306.856931430553, 14398.568042958519, 14402.495912643008, 14426.278686065654, 14497.420103195966, 5949.762009519571, 6033.0, 6056.303525094582, 6056.757729690763, 6121.632702037892, 963.4860677724741, 976.1946394224793, 977.8093271811929, 998.0568362897138, 1008.4554340655964, 28779.42441151207, 28785.424291514053, 28839.134825955254, 29133.708662913523, 29405.0, 1035.9378437293788, 1060.3584831177027, 1067.5356220044305, 1080.3463904337762, 1117.7149826794116, 15212.619684507976, 15228.923855380652, 15275.770863437057, 17098.487045388658, 17680.90584147769, 379881.0, 381428.0, 382708.0, 382788.0, 385205.0, 56600.433995659514, 56682.0, 56786.7160664194, 57059.0, 57178.42821571731, 1331.227887825059, 1341.4298922957703, 1369.452219112343, 1378.421063153478, 1390.8748212660707, 29217.0, 30426.239344016576, 31764.841175793972, 33166.17084572905, 33236.335273294884, 51021.744891276794, 51955.480445196576, 52149.7392513035, 52232.73883630557, 52237.0, 284091.0, 286502.0, 288656.0, 289884.0, 290037.0, 43272.783636081615, 43591.0, 44073.77963110164, 44425.333619996374, 44581.55418445774, 44112.558874410846, 45292.0, 45345.0, 45619.543804562854, 46122.76938615285, 37230.8138459306, 37334.43998339973, 38261.0, 38415.23169536538, 38865.41701874418, 38623.613763862, 40037.39943900902, 41605.37591936184, 42832.57167428286, 43030.78484607557, 40942.0, 41103.1779364417, 41600.167996640506, 41966.0, 42114.57885421229, 31299.37401252008, 31607.0, 34088.48867266943, 34178.14554636154, 34851.65148348484, 32779.836100819346, 34516.30967380588, 34755.30489390148, 34761.304773904885, 34803.0], "unit": "ms", "name": "ext.html", "value": 18833.64169680412}, {"lowerIsBetter": false, "replicates": [361.0738455860717, 361.45420415173083, 361.95113659655937, 362.87662194853743, 364.6098674418372, 16611.169441527934, 17114.400995965145, 17812.10939453027, 17815.64368712628, 18321.175547100378, 11264.605738799144, 13024.348782560857, 16967.83032169678, 17367.392141275057, 17385.304587816474, 4856.344393506884, 5022.82420115296, 5036.924446133311, 5049.368828896388, 5055.747212639371, 5038.773255203525, 5066.949330506703, 5088.974555127238, 5094.1594636884865, 5108.565771909392, 3672.9081772955756, 3714.0, 5258.47415258473, 5367.114426119686, 5421.484958928895, 5403.729813509329, 5424.1321388577835, 5450.373207081197, 5456.672599644034, 5468.644538105031, 5020.748962551857, 5196.532312091892, 5202.531772140525, 5262.815801446931, 5270.841874743764, 12794.42425090873, 13393.799093013613, 15838.683226335408, 21485.785142148536, 21542.246021389175, 2025.2405347994502, 2026.807453291935, 2048.047657839097, 2073.5541858500496, 2074.6369385357502, 9332.300077494216, 9494.098060684293, 9781.0, 11662.650120496399, 12069.456874440672, 1622.9288669478206, 1657.4447560067392, 1770.3184274054581, 1773.9556511087326, 1789.498940296719, 62436.0, 62709.37290627127, 62713.37286627121, 64053.0, 64920.67539662271, 10272.794544109076, 10505.68482945513, 10509.054185123223, 10548.103411209979, 10580.735481612868, 13659.726805463835, 13690.794638080437, 13734.793978090336, 13742.725145497034, 13775.38010789517, 7642.121156067124, 7682.308592226726, 7691.384689224848, 7751.651175697107, 7974.60126993648, 7190.7483238086415, 7216.8917466238045, 7221.53060051095, 7229.0, 7415.295546923034, 4395.1868904252815, 4404.405405270248, 4429.867103986886, 4500.639948804088, 4525.909481810411, 1861.26480040386, 1922.807719228094, 1927.4121392974996, 1951.9024048797503, 1957.2464601128383, 443.74706417341804, 451.93672885796013, 463.1894185175942, 859.3726579596929, 1043.8068957242933, 11073.944630276957, 15325.540233793005, 16677.332906683598, 16850.325986960383, 17625.38311159129, 930.7719608695913, 937.1659223291276, 1000.8548760429696, 1029.7322696098918, 1044.3264094658903, 9970.800583988279, 10135.746606334753, 10212.642557510451, 10251.7437064074, 10283.640072597573, 500981.0, 510973.0, 533952.0, 550237.0, 551551.0, 84313.66685605528, 85623.67970275585, 85928.28143437096, 86912.13087869166, 88247.0, 1186.7329850783676, 1187.5368606243571, 1190.1966172833304, 1191.2316555821446, 1197.3773637708343, 15693.686126277638, 15895.52313430599, 16039.438619648256, 16117.0, 16272.43046493367, 33967.66032339645, 34365.0, 37081.258374831814, 37108.81445592863, 37817.432738509495, 43164.0, 44824.55175448204, 48513.27230091622, 48663.0, 49592.00815983732, 9595.376300540442, 9881.209503239867, 9986.550605222928, 10015.59937602517, 10152.847707284249, 18212.81787182164, 18386.377960961923, 18432.907835460737, 18495.167717452478, 18605.162767675756, 13866.306684665928, 15013.849861501245, 15059.6988060236, 15257.6185595361, 15671.843281567495, 14160.929195354369, 14332.426702931765, 14413.0, 14676.853231467549, 14806.555803325917, 14670.413183472541, 14916.254187290375, 14956.850431495546, 15050.397984080515, 15162.620934476727, 9634.084761947885, 9865.852012219966, 9946.204303655835, 10074.496275186359, 10113.797724045626, 10402.791944160923, 10455.215858810772, 14575.781363279348, 15016.399344026117, 15369.846301536842], "unit": "ms", "name": "jquery.html", "value": 8810.669357292536}, {"lowerIsBetter": false, "replicates": [1460.8612181842725, 1507.4045751927988, 1600.6638605892763, 1637.860781833544, 1744.1540852686444, 7384.298491643292, 7648.235176482349, 7655.885161722578, 7746.4190185736015, 8018.198180181979, 7735.7679269622, 7743.070831500214, 7819.374450043997, 7845.725399611015, 7846.960765196166, 2243.2372993182275, 2282.3267136194863, 2284.8514846535004, 2294.3346429535413, 2302.804261637763, 2041.9591608167827, 2077.356019633918, 2097.4966008158085, 2108.1988844239168, 2110.198124712607, 2226.92205772798, 2280.407094155515, 2288.0275882749847, 2295.908163673451, 2301.0680674326923, 2227.276135256032, 2234.3743751749535, 2285.839991200625, 2296.8392212545127, 2303.1593468384003, 2294.598445272071, 2325.8837058147024, 2327.650852372141, 2329.208069256444, 2337.812974961999, 7669.348105411047, 7768.184340644264, 7772.533647981083, 7780.299773020399, 7792.103908050533, 4690.530946905316, 4770.0936822003705, 4789.784459699287, 4806.975965120152, 4808.278758186265, 3072.4469595472806, 3086.3055812442244, 3126.812391256532, 3148.5277208418693, 3162.0513845846153, 957.8802649668792, 959.3332633819498, 960.8750862387915, 961.6874515782399, 993.4883534979491, 10125.038121378531, 10142.594296228142, 10185.388876667424, 10245.743856403575, 10272.537735801907, 4106.897327566835, 4108.198901214294, 4160.271952408317, 4181.247375472383, 4270.231358355463, 8502.489850608983, 8509.808626792254, 8516.40385173032, 8525.95737021311, 8532.104129066483, 4695.3426520287185, 4707.929381059287, 4714.5992590630185, 4723.48041715411, 4729.881752956135, 4330.978345108318, 4332.1119170569955, 4337.91552111972, 4340.392345071693, 4344.869653910325, 3413.010227034132, 3418.6239513653977, 3418.9145271368416, 3433.9313213736086, 3440.7935523868646, 1089.54239219528, 1102.818035024228, 1103.4703342395535, 1106.9335839849473, 1110.5724296146043, 956.8660387545749, 957.4542510768795, 1048.538642997094, 1138.6242539961959, 1193.1349771415655, 7250.854982900312, 7318.451116166309, 7338.633068346561, 7342.926570734331, 7399.704011839466, 1143.4702484142017, 1149.350616901448, 1155.5089087137933, 1187.1986409173774, 1193.3496244546743, 5762.164486149569, 5791.507721843606, 5806.21616081832, 5810.360860305363, 5830.329512106162, 9093.863592046124, 9201.861972070425, 9226.35415520907, 9344.205742511827, 9382.48396338208, 9246.676366327147, 9288.489133097744, 9399.201067909304, 9457.574409151604, 9473.431594104239, 7145.464090193256, 7146.2496437874315, 7193.748218812421, 7339.0, 7425.1461081975185, 7288.67200975968, 7313.414926805736, 7317.12194536659, 7340.70637174507, 7349.30181632744, 8074.0, 8299.087100418828, 8319.958400207961, 8473.237408633006, 8483.236508714246, 8419.8737018946, 8426.0, 8526.78683032929, 8568.057513673615, 8722.040575536812, 6399.168108145911, 6832.93167068323, 6930.7227710891, 6960.651967401711, 6979.1973922999505, 7379.225181356095, 7385.29839665231, 7446.180920098893, 7447.478676492594, 7491.400687945061, 6821.386075253251, 6867.793966181022, 6942.444604431533, 6951.687174077279, 7126.322999315057, 6800.217974932748, 6812.591244525344, 6850.177978642595, 6953.23514413404, 7004.649767511706, 7030.156381234285, 7060.505764596429, 7079.858402832017, 7083.82290442743, 7113.893291600527, 6583.736650534117, 6593.736250550116, 6614.6361950093205, 6628.337166283333, 6636.203655561363, 6382.585131966407, 6428.839279017875, 6435.646039467874, 6449.322821103717, 6595.901061484177], "unit": "ms", "name": "mootools.html", "value": 4447.077054621859}, {"lowerIsBetter": false, "replicates": [338.0686209492847, 346.6775898414475, 347.8591170575917, 348.39379479705286, 352.381570344046, 6197.628142311464, 6252.531060170489, 6283.120363149163, 6288.245410550729, 6358.109864618957, 6090.0, 6185.938140618592, 6186.56694031418, 6233.438990490867, 6264.248290205171, 4794.808207671689, 4845.885446347343, 4870.65905386623, 4878.170710979124, 4880.341153944208, 4882.584980276681, 4886.071646387193, 4891.315215869781, 4891.5353041461185, 4896.877578060541, 4742.691725037878, 4847.927281090787, 4849.709017458946, 4862.659613827033, 4868.707877527359, 4819.397575303087, 4826.565609095163, 4828.203346447867, 4828.903421931559, 4830.589399901012, 4226.028013556868, 4228.873133805991, 4242.809073591696, 4253.128108737701, 4266.957330426718, 5886.175935369051, 5903.49820265278, 5908.14331921869, 5928.318243402021, 5934.198883150762, 5450.427705090988, 5632.718364081779, 5644.096944488903, 5658.773649054074, 5667.971660141714, 6715.832104197385, 6716.227633822075, 6722.697478613474, 6725.798226053226, 6736.292689267654, 4709.62323014158, 4714.151452738506, 4720.952790472085, 4725.31482934976, 4737.052589482118, 6855.794326170223, 6908.101946746941, 6910.965445172742, 6955.860882782316, 6978.197507286676, 4801.68789028712, 4815.831445899376, 4817.445993710698, 4817.964137710339, 4821.566059054702, 4811.422629284508, 4873.512648735169, 4874.95125048745, 4875.414950205998, 4884.291777692215, 5489.725513724298, 5541.445855414427, 5570.832875013675, 5582.33012038558, 5585.106382978785, 6632.668366581652, 6698.8995165072565, 6732.528722989442, 6741.561798483181, 6749.460043196533, 1687.0889719551305, 1723.3623559283146, 1738.6261953679766, 1739.2781995471737, 1743.102302314309, 1174.1780753472603, 1183.0949323767325, 1196.0312147160828, 1196.0969468051564, 1208.3414539076225, 6457.935420645827, 6499.1876015498065, 6501.0, 6507.121538592324, 6516.0, 6787.1176747023555, 6828.146481689789, 6834.453243740489, 6839.726410943606, 6846.383825455733, 6316.589421687576, 6439.581427207217, 6449.580777249464, 6455.4835613150835, 6478.319776423502, 3744.7378683491893, 3783.829727662262, 3791.7914514701956, 3792.5259342582176, 3792.962070379316, 7311.89032164518, 7313.085864266966, 7321.670524826395, 7358.558486490828, 7367.26327367259, 7020.122484689414, 7156.248593897566, 7179.210286868441, 7182.928170718331, 7199.460040496983, 7300.9634951825965, 7309.0, 7314.08573928259, 7316.158641756159, 7334.853302933911, 6902.585844849124, 6906.965465172642, 6953.269906659728, 6957.512974091765, 6975.267596902252, 6747.527673062838, 6871.9656401717675, 6877.4841886857685, 6900.275471075464, 6900.6204658744255, 7268.0914885639295, 7276.345128938421, 7289.8177545559665, 7306.7077316906725, 7331.450141239322, 4668.509806470408, 4711.0, 4711.78796954131, 4713.057388522378, 4720.45714742809, 6691.1636045494315, 6764.120664313607, 6907.378335949788, 6940.375366217065, 6983.1620205575655, 6735.764248251287, 6749.460043196631, 6754.493412994143, 6764.5603035802515, 6784.728610855511, 6728.529002969745, 6730.125083739083, 6731.966340168267, 6732.528722989344, 6780.423663988616, 6523.380278873499, 6613.6693165340575, 6632.403083722488, 6638.20341559016, 6646.5347425681675, 6696.4977626679165, 6791.286914873867, 6791.93208067913, 6795.864082718417, 6800.625965571743, 6555.573887697285, 6568.7372505099265, 6593.670316484253, 6610.0, 6635.037919501742], "unit": "ms", "name": "prototype.html", "value": 5080.61792946551}, {"lowerIsBetter": false, "replicates": [769.4536878816041, 812.8740045292978, 820.723774530605, 831.6423937706786, 857.2916925061396, 13723.451061957523, 14230.359633816477, 14250.28748562573, 14314.713705725879, 14316.71366572668, 13583.185008899472, 13714.657133571665, 14386.424543018293, 14901.180435076067, 15060.472883449082, 810.2181394953868, 829.1832544943226, 829.6515463505342, 830.900291964965, 840.0423517190408, 832.633641197874, 836.5022811427191, 840.7351684219482, 843.3253397282181, 844.1853611265128, 878.4509681449094, 890.1543533643065, 890.9509976951265, 892.576026387464, 892.6117139044536, 882.3602887906248, 884.9734507964707, 885.1148851148852, 886.6231851463135, 888.7467071884514, 648.993510064898, 649.5161104976822, 651.2217899609968, 652.103357882911, 654.8722999015193, 14238.501652442115, 14244.715105697931, 14356.282185890665, 14367.856321436757, 14369.856301436956, 10678.946605267001, 11977.880221197764, 12344.95067919219, 12543.808338207855, 12906.225626462443, 10461.790764184674, 10516.263861529696, 10562.894371056267, 10680.679579612624, 10860.945695271474, 3657.487951686766, 3670.2475992421755, 3706.4069748840325, 3739.738218324718, 3852.8651497197466, 49546.0, 49659.0, 49779.50220497821, 49779.75110124463, 50221.74889125531, 10109.34289271195, 10481.42352170638, 10524.31591946521, 10541.262111652264, 10898.945505272422, 18876.716849247274, 18969.051547422572, 19005.95467249287, 19060.142293596822, 19096.80903190978, 7582.0, 7775.92224077752, 7961.482503637245, 7977.401694872908, 7984.520928744294, 4350.782460877007, 4369.541198174209, 4389.100234451994, 4420.668449866273, 4628.60656844172, 3154.873805047818, 3166.825824579624, 3168.12876458971, 3171.20719820045, 3171.698688624577, 593.6556797057697, 596.6509591888794, 609.0681257675764, 612.3967891626744, 614.532954954229, 556.7800718716165, 614.3273115938041, 646.873859597374, 684.4968947823346, 700.4046560423701, 8546.0, 8765.517896515752, 8931.151540603632, 8968.730938071869, 9840.901590984142, 537.9004884096455, 661.4609093588806, 697.5500801982693, 697.5540828308142, 698.8847583643122, 3841.5966323536186, 3960.148568057881, 4020.8592699255387, 4214.7471151731, 4246.235677578004, 54297.0, 55794.0, 55966.72016639891, 56618.716906415204, 57827.421725783046, 50760.0, 51028.74485627548, 51356.0, 51356.48643513592, 52155.21767173496, 47715.76142119267, 48382.27426588604, 48388.0, 48389.5161048385, 49274.0, 8946.507942063248, 9169.266458683156, 9210.493422861808, 9314.673986410444, 9360.11078947499, 35864.0, 36621.633783661826, 37027.0, 37574.62425375821, 38687.41968870525, 42663.5733642672, 42820.78589607032, 45833.54166458426, 45918.770406147756, 47018.29472557983, 11176.720581985515, 11388.202825802446, 11395.487203076047, 11438.82841757391, 11443.771124577295, 12254.38728063611, 12608.306543140216, 12755.681107972376, 12796.168249063781, 12925.15986460877, 11485.483153257956, 11911.285322880654, 11932.880671193177, 12262.632121036384, 12299.323537205182, 10884.945575272073, 11402.657920262405, 11515.078793696652, 11790.351530665896, 11892.643220703392, 10671.679849604525, 11707.88292117068, 11798.52805887755, 12167.634970950885, 12178.6955326114, 9794.0, 9799.951000244953, 10077.053390895608, 10153.0, 10300.639477618248, 8900.910990890266, 9036.502992335221, 9601.903980960102, 9794.510274486105, 10058.34620749649], "unit": "ms", "name": "yui.html", "value": 6512.784323456656}], "name": "dromaeo_css", "value": 7152.496461032821}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 105.0, 111.0, 111.0, 110.0, 123.0, 111.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 111.1}, {"lowerIsBetter": true, "replicates": [150.0, 124.0, 151.0, 178.0, 126.0, 150.0, 157.0, 132.0, 168.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.3}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 163.0, 163.0, 161.0, 163.0, 162.0, 172.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 163.2}, {"lowerIsBetter": true, "replicates": [72.0, 76.0, 73.0, 72.0, 74.0, 140.0, 72.0, 79.0, 75.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.5}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 135.0, 92.0, 92.0, 94.0, 138.0, 93.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 111.0, 111.0, 112.0, 111.0, 111.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.6}, {"lowerIsBetter": true, "replicates": [148.0, 146.0, 146.0, 145.0, 149.0, 146.0, 145.0, 147.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.4}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 98.0, 97.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 64.0, 64.0, 68.0, 65.0, 67.0, 64.0, 63.0, 62.0], "unit": "ms", "name": "json-parse-financial", "value": 65.0}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 68.0, 69.0, 70.0, 70.0, 68.0, 70.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.1}, {"lowerIsBetter": true, "replicates": [85.0, 91.0, 85.0, 85.0, 92.0, 83.0, 86.0, 84.0, 86.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.2}, {"lowerIsBetter": true, "replicates": [124.0, 127.0, 120.0, 116.0, 117.0, 124.0, 117.0, 121.0, 117.0, 130.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.3}, {"lowerIsBetter": true, "replicates": [141.0, 150.0, 151.0, 163.0, 152.0, 150.0, 147.0, 148.0, 153.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.5}, {"lowerIsBetter": true, "replicates": [73.0, 67.0, 67.0, 70.0, 69.0, 66.0, 68.0, 67.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.0}], "name": "kraken", "value": 1519.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [184.1959845275373], "unit": "score", "name": "Richards", "value": 191.654558}, {"lowerIsBetter": false, "replicates": [192.34468166955185], "unit": "score", "name": "DeltaBlue", "value": 343.7474820000001}, {"lowerIsBetter": false, "replicates": [282.4060999717594], "unit": "score", "name": "Encrypt", "value": 942.5469209999999}, {"lowerIsBetter": false, "replicates": [5060.606060606061], "unit": "score", "name": "Decrypt", "value": 52.59864071856286}, {"lowerIsBetter": false, "replicates": [1050.420168067227], "unit": "score", "name": "RayTrace", "value": 704.469528}, {"lowerIsBetter": false, "replicates": [700.9803921568628], "unit": "score", "name": "Earley", "value": 950.7584055944051}, {"lowerIsBetter": false, "replicates": [12228.915662650603], "unit": "score", "name": "Boyer", "value": 54.498944827586165}, {"lowerIsBetter": false, "replicates": [27459.45945945946], "unit": "score", "name": "RegExp", "value": 33.17563484251969}, {"lowerIsBetter": false, "replicates": [642.2607578676943], "unit": "score", "name": "Splay", "value": 126.88148699999995}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 20914.05812806522}]} 07:07:51 INFO - RETURN: dromaeo_css: 7152.50 07:07:51 INFO - RETURN: kraken: 1519.40 07:07:51 INFO - RETURN: v8_7: 20914.06 07:07:51 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "20914.06"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1519.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "7152.50"}}} 07:07:51 INFO - Return code: 0 07:07:51 INFO - # TBPL SUCCESS # 07:07:51 INFO - Running post-action listener: _resource_record_post_action 07:07:51 INFO - Running post-run listener: _resource_record_post_run 07:07:52 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 12288; Write bytes: 525684736; Read time: 428; Write time: 615428 07:07:52 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 238190592; Read time: 120; Write time: 414696 07:07:52 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 8192; Write bytes: 287494144; Read time: 308; Write time: 200732 07:07:52 INFO - Running post-run listener: _upload_blobber_files 07:07:52 INFO - Blob upload gear active. 07:07:52 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:07:52 INFO - Copying logs to upload dir... 07:07:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.281635 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-23 07:07:51.292213) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 07:07:51.295640) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010012 build_url: 'https://queue.taskcluster.net/v1/task/WdWDprL5QV2RJs1ZFIdh4A/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-23 07:07:51.345998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 07:07:51.346326) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1448289701.799218-111582460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004581 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 07:07:51.399276) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 07:07:51.399600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 07:07:51.399897) ========= ========= Total master_lag: 0.19 =========