builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-036 starttime: 1447878695.21 results: success (0) buildid: 20151118103030 builduid: c0ac033cd834429481f1c3a7209e7b43 revision: 4a9ebc74d62a0b07a026a54631b15c21660a3933 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.214538) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.215080) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.215471) ========= 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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.002979 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.244064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.244371) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.308140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.308397) ========= 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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.049285 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.369401) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.369682) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.370026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.370305) ========= 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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-18 12:31:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 12.8M=0.001s 2015-11-18 12:31:35 (12.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.231158 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.614958) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.615284) ========= 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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.084614 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.710703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:35.711021) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4a9ebc74d62a0b07a026a54631b15c21660a3933 --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 4a9ebc74d62a0b07a026a54631b15c21660a3933 --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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-18 12:31:35,763 truncating revision to first 12 chars 2015-11-18 12:31:35,763 Setting DEBUG logging. 2015-11-18 12:31:35,763 attempt 1/10 2015-11-18 12:31:35,763 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/4a9ebc74d62a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 12:31:36,022 unpacking tar archive at: mozilla-inbound-4a9ebc74d62a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.535388 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:36.262861) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:36.263803) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:36.275028) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:36.275309) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 12:31:36.275693) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-11-18 12:31:36.276010) ========= /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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:31:36 INFO - MultiFileLogger online at 20151118 12:31:36 in /builds/slave/test-pgo 12:31:36 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 12:31:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 12:31:36 INFO - {'append_to_log': False, 12:31:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 12:31:36 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 12:31:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 12:31:36 INFO - 'branch': 'Mozilla-Inbound', 12:31:36 INFO - 'buildbot_json_path': 'buildprops.json', 12:31:36 INFO - 'config_files': ('talos/linux_config.py',), 12:31:36 INFO - 'default_actions': ('clobber', 12:31:36 INFO - 'read-buildbot-config', 12:31:36 INFO - 'download-and-extract', 12:31:36 INFO - 'populate-webroot', 12:31:36 INFO - 'create-virtualenv', 12:31:36 INFO - 'install', 12:31:36 INFO - 'run-tests'), 12:31:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:31:36 INFO - 'download_minidump_stackwalk': True, 12:31:36 INFO - 'download_symbols': 'ondemand', 12:31:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:31:36 INFO - 'tooltool.py': '/tools/tooltool.py', 12:31:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:31:36 INFO - '/tools/misc-python/virtualenv.py')}, 12:31:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:31:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:31:36 INFO - 'installer_path': 'installer.exe', 12:31:36 INFO - 'log_level': 'info', 12:31:36 INFO - 'log_name': 'talos', 12:31:36 INFO - 'log_to_console': True, 12:31:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:31:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:31:36 INFO - 'opt_config_files': (), 12:31:36 INFO - 'pip_index': False, 12:31:36 INFO - 'sps_profile': False, 12:31:36 INFO - 'sps_profile_interval': 0, 12:31:36 INFO - 'suite': 'dromaeojs-e10s', 12:31:36 INFO - 'system_bits': '64', 12:31:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:31:36 INFO - 'title': 'talos-linux64-ix-036', 12:31:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:31:36 INFO - 'use_talos_json': True, 12:31:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 12:31:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:31:36 INFO - 'work_dir': 'build'} 12:31:36 INFO - ##### 12:31:36 INFO - ##### Running clobber step. 12:31:36 INFO - ##### 12:31:36 INFO - Running pre-action listener: _resource_record_pre_action 12:31:36 INFO - Running main action method: clobber 12:31:36 INFO - rmtree: /builds/slave/test-pgo/build 12:31:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 12:31:37 INFO - Running post-action listener: _resource_record_post_action 12:31:37 INFO - ##### 12:31:37 INFO - ##### Running read-buildbot-config step. 12:31:37 INFO - ##### 12:31:37 INFO - Running pre-action listener: _resource_record_pre_action 12:31:37 INFO - Running main action method: read_buildbot_config 12:31:37 INFO - Using buildbot properties: 12:31:37 INFO - { 12:31:37 INFO - "properties": { 12:31:37 INFO - "buildnumber": 444, 12:31:37 INFO - "product": "firefox", 12:31:37 INFO - "script_repo_revision": "production", 12:31:37 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 12:31:37 INFO - "repository": "", 12:31:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 12:31:37 INFO - "buildid": "20151118103030", 12:31:37 INFO - "slavename": "talos-linux64-ix-036", 12:31:37 INFO - "pgo_build": "True", 12:31:37 INFO - "basedir": "/builds/slave/test-pgo", 12:31:37 INFO - "project": "", 12:31:37 INFO - "platform": "ubuntu64_hw", 12:31:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:31:37 INFO - "slavebuilddir": "test", 12:31:37 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 12:31:37 INFO - "branch": "mozilla-inbound", 12:31:37 INFO - "repo_path": "integration/mozilla-inbound", 12:31:37 INFO - "stage_platform": "linux64-pgo", 12:31:37 INFO - "builduid": "c0ac033cd834429481f1c3a7209e7b43", 12:31:37 INFO - "revision": "4a9ebc74d62a0b07a026a54631b15c21660a3933" 12:31:37 INFO - }, 12:31:37 INFO - "sourcestamp": { 12:31:37 INFO - "repository": "", 12:31:37 INFO - "hasPatch": false, 12:31:37 INFO - "project": "", 12:31:37 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 12:31:37 INFO - "changes": [ 12:31:37 INFO - { 12:31:37 INFO - "category": null, 12:31:37 INFO - "files": [ 12:31:37 INFO - { 12:31:37 INFO - "url": null, 12:31:37 INFO - "name": "https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 12:31:37 INFO - } 12:31:37 INFO - ], 12:31:37 INFO - "repository": "", 12:31:37 INFO - "rev": "4a9ebc74d62a0b07a026a54631b15c21660a3933", 12:31:37 INFO - "who": "sendchange", 12:31:37 INFO - "when": 1447878650, 12:31:37 INFO - "number": 6678674, 12:31:37 INFO - "comments": "", 12:31:37 INFO - "project": "", 12:31:37 INFO - "at": "Wed 18 Nov 2015 12:30:50", 12:31:37 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 12:31:37 INFO - "revlink": "", 12:31:37 INFO - "properties": [ 12:31:37 INFO - [ 12:31:37 INFO - "buildid", 12:31:37 INFO - "20151118103030", 12:31:37 INFO - "Change" 12:31:37 INFO - ], 12:31:37 INFO - [ 12:31:37 INFO - "builduid", 12:31:37 INFO - "c0ac033cd834429481f1c3a7209e7b43", 12:31:37 INFO - "Change" 12:31:37 INFO - ], 12:31:37 INFO - [ 12:31:37 INFO - "pgo_build", 12:31:37 INFO - "True", 12:31:37 INFO - "Change" 12:31:37 INFO - ] 12:31:37 INFO - ], 12:31:37 INFO - "revision": "4a9ebc74d62a0b07a026a54631b15c21660a3933" 12:31:37 INFO - } 12:31:37 INFO - ], 12:31:37 INFO - "revision": "4a9ebc74d62a0b07a026a54631b15c21660a3933" 12:31:37 INFO - } 12:31:37 INFO - } 12:31:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 12:31:37 INFO - Running post-action listener: _resource_record_post_action 12:31:37 INFO - ##### 12:31:37 INFO - ##### Running download-and-extract step. 12:31:37 INFO - ##### 12:31:37 INFO - Running pre-action listener: _resource_record_pre_action 12:31:37 INFO - Running main action method: download_and_extract 12:31:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 12:31:37 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')]} 12:31:37 INFO - https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:31:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/test_packages.json 12:31:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/test_packages.json 12:31:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 12:31:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 12:31:42 INFO - Downloaded 1302 bytes. 12:31:42 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 12:31:42 INFO - Using the following test package requirements: 12:31:42 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:31:42 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:31:42 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'jsshell-linux-x86_64.zip'], 12:31:42 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:31:42 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:31:42 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:31:42 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:31:42 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:31:42 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:31:42 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:31:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:31:42 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:42 INFO - https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:31:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:31:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:31:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:46 INFO - Downloaded 22462199 bytes. 12:31:46 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 12:31:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:31:46 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 12:31:47 INFO - Return code: 0 12:31:47 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 12:31:47 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')]} 12:31:47 INFO - https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:31:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:31:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:31:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:55 INFO - Downloaded 22462199 bytes. 12:31:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 12:31:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:31:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:31:57 INFO - Return code: 0 12:31: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')]} 12:31:57 INFO - https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:31:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:31:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:31:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:58 INFO - Downloaded 11130997 bytes. 12:31:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 12:31:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:31:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 12:31:58 INFO - Return code: 0 12:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:58 INFO - https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:31:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:31:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:31:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zPp0IkguTLOOhI-pgMKuTg/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 12:31:59 INFO - Downloaded 57204904 bytes. 12:31:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:31:59 INFO - mkdir: /builds/slave/test-pgo/properties 12:31:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 12:31:59 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 12:31:59 INFO - Contents: 12:31:59 INFO - build_url:https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:31:59 INFO - Running post-action listener: _resource_record_post_action 12:31:59 INFO - Running post-action listener: set_extra_try_arguments 12:31:59 INFO - ##### 12:31:59 INFO - ##### Running populate-webroot step. 12:31:59 INFO - ##### 12:31:59 INFO - Running pre-action listener: _resource_record_pre_action 12:31:59 INFO - Running main action method: populate_webroot 12:31:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:31:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:31:59 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 12:31:59 INFO - '../fennec_ids.txt'], 12:31:59 INFO - 'tests': ['tcheck2']}, 12:31:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:31:59 INFO - '--tppagecycles', 12:31:59 INFO - '7'], 12:31:59 INFO - 'tests': ['tsvgm']}}, 12:31:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:31:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:31:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:31:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:31:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:31:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:31:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'tests': ['damp', 'tps']}, 12:31:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['damp', 'tps']}, 12:31:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:31:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:31:59 INFO - 'other': {'tests': ['a11yr', 12:31:59 INFO - 'ts_paint', 12:31:59 INFO - 'tpaint', 12:31:59 INFO - 'sessionrestore', 12:31:59 INFO - 'sessionrestore_no_auto_restore']}, 12:31:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['a11yr', 12:31:59 INFO - 'ts_paint', 12:31:59 INFO - 'tpaint', 12:31:59 INFO - 'sessionrestore', 12:31:59 INFO - 'sessionrestore_no_auto_restore']}, 12:31:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['a11yr', 12:31:59 INFO - 'ts_paint', 12:31:59 INFO - 'tpaint', 12:31:59 INFO - 'sessionrestore', 12:31:59 INFO - 'sessionrestore_no_auto_restore']}, 12:31:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['a11yr', 12:31:59 INFO - 'ts_paint', 12:31:59 INFO - 'tpaint', 12:31:59 INFO - 'sessionrestore', 12:31:59 INFO - 'sessionrestore_no_auto_restore']}, 12:31:59 INFO - 'other_l64': {'tests': ['a11yr', 12:31:59 INFO - 'ts_paint', 12:31:59 INFO - 'tpaint', 12:31:59 INFO - 'sessionrestore', 12:31:59 INFO - 'sessionrestore_no_auto_restore']}, 12:31:59 INFO - 'other_nol64': {'tests': ['a11yr', 12:31:59 INFO - 'ts_paint', 12:31:59 INFO - 'tpaint', 12:31:59 INFO - 'sessionrestore', 12:31:59 INFO - 'sessionrestore_no_auto_restore']}, 12:31:59 INFO - 'svgr': {'tests': ['tsvgx', 12:31:59 INFO - 'tsvgr_opacity', 12:31:59 INFO - 'tart', 12:31:59 INFO - 'tscrollx', 12:31:59 INFO - 'cart']}, 12:31:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['tsvgx', 12:31:59 INFO - 'tsvgr_opacity', 12:31:59 INFO - 'tart', 12:31:59 INFO - 'tscrollx', 12:31:59 INFO - 'cart']}, 12:31:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'tests': ['tp5o']}, 12:31:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'talos_options': ['--e10s'], 12:31:59 INFO - 'tests': ['tp5o']}, 12:31:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'talos_options': ['--xperf_path', 12:31:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:31:59 INFO - 'tests': ['tp5n']}, 12:31:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:59 INFO - 'talos_options': ['--e10s', 12:31:59 INFO - '--xperf_path', 12:31:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:31:59 INFO - 'tests': ['tp5n']}}, 12:31:59 INFO - 'talos.zip': {'path': '', 12:31:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:31:59 INFO - Running post-action listener: _resource_record_post_action 12:31:59 INFO - ##### 12:31:59 INFO - ##### Running create-virtualenv step. 12:31:59 INFO - ##### 12:31:59 INFO - Running pre-action listener: _resource_record_pre_action 12:31:59 INFO - Running main action method: create_virtualenv 12:31:59 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 12:31:59 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 12:31:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 12:31:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:31:59 INFO - Using real prefix '/usr' 12:31:59 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 12:32:00 INFO - Installing distribute.............................................................................................................................................................................................done. 12:32:03 INFO - Installing pip.................done. 12:32:03 INFO - Return code: 0 12:32:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 12:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:03 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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32:03 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 12:32:03 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 12:32:03 INFO - Using env: {'DISPLAY': ':0', 12:32:03 INFO - 'HOME': '/home/cltbld', 12:32:03 INFO - 'LANG': 'en_US.UTF-8', 12:32:03 INFO - 'LANGUAGE': 'en_US:en', 12:32:03 INFO - 'LOGNAME': 'cltbld', 12:32:03 INFO - 'MAIL': '/var/mail/cltbld', 12:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:03 INFO - 'MOZ_NO_REMOTE': '1', 12:32:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:03 INFO - 'NO_EM_RESTART': '1', 12:32:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:03 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:03 INFO - 'SHELL': '/bin/bash', 12:32:03 INFO - 'SHLVL': '1', 12:32:03 INFO - 'TERM': 'linux', 12:32:03 INFO - 'TMOUT': '86400', 12:32:03 INFO - 'USER': 'cltbld', 12:32:03 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:03 INFO - '_': '/tools/buildbot/bin/python'} 12:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:04 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 12:32:04 INFO - Cleaning up... 12:32:04 INFO - Return code: 0 12:32:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 12:32: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')]} 12:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32: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')]} 12:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:04 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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32:04 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 12:32:04 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 12:32:04 INFO - Using env: {'DISPLAY': ':0', 12:32:04 INFO - 'HOME': '/home/cltbld', 12:32:04 INFO - 'LANG': 'en_US.UTF-8', 12:32:04 INFO - 'LANGUAGE': 'en_US:en', 12:32:04 INFO - 'LOGNAME': 'cltbld', 12:32:04 INFO - 'MAIL': '/var/mail/cltbld', 12:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:04 INFO - 'MOZ_NO_REMOTE': '1', 12:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:04 INFO - 'NO_EM_RESTART': '1', 12:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:04 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:04 INFO - 'SHELL': '/bin/bash', 12:32:04 INFO - 'SHLVL': '1', 12:32:04 INFO - 'TERM': 'linux', 12:32:04 INFO - 'TMOUT': '86400', 12:32:04 INFO - 'USER': 'cltbld', 12:32:04 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:04 INFO - '_': '/tools/buildbot/bin/python'} 12:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:04 INFO - Downloading/unpacking psutil>=0.7.1 12:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:06 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 12:32:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:32:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 12:32:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:06 INFO - Installing collected packages: psutil 12:32:06 INFO - Running setup.py install for psutil 12:32:06 INFO - building 'psutil._psutil_linux' extension 12:32:06 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 12:32:07 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 12:32:07 INFO - building 'psutil._psutil_posix' extension 12:32:07 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 12:32:07 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 12:32:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:07 INFO - Successfully installed psutil 12:32:07 INFO - Cleaning up... 12:32:07 INFO - Return code: 0 12:32:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 12:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32:08 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 12:32:08 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 12:32:08 INFO - Using env: {'DISPLAY': ':0', 12:32:08 INFO - 'HOME': '/home/cltbld', 12:32:08 INFO - 'LANG': 'en_US.UTF-8', 12:32:08 INFO - 'LANGUAGE': 'en_US:en', 12:32:08 INFO - 'LOGNAME': 'cltbld', 12:32:08 INFO - 'MAIL': '/var/mail/cltbld', 12:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:08 INFO - 'MOZ_NO_REMOTE': '1', 12:32:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:08 INFO - 'NO_EM_RESTART': '1', 12:32:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:08 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:08 INFO - 'SHELL': '/bin/bash', 12:32:08 INFO - 'SHLVL': '1', 12:32:08 INFO - 'TERM': 'linux', 12:32:08 INFO - 'TMOUT': '86400', 12:32:08 INFO - 'USER': 'cltbld', 12:32:08 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:08 INFO - '_': '/tools/buildbot/bin/python'} 12:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:32:08 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 12:32:08 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 12:32:08 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 12:32:08 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 12:32:08 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 12:32:08 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 12:32:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:32:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:32:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:32:09 INFO - Installing collected packages: mozsystemmonitor 12:32:09 INFO - Running setup.py install for mozsystemmonitor 12:32:10 INFO - Successfully installed mozsystemmonitor 12:32:10 INFO - Cleaning up... 12:32:10 INFO - Return code: 0 12:32:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 12:32: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')]} 12:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32: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')]} 12:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32: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', '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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 12:32: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 blobuploader==1.2.4 12:32:10 INFO - Using env: {'DISPLAY': ':0', 12:32:10 INFO - 'HOME': '/home/cltbld', 12:32:10 INFO - 'LANG': 'en_US.UTF-8', 12:32:10 INFO - 'LANGUAGE': 'en_US:en', 12:32:10 INFO - 'LOGNAME': 'cltbld', 12:32:10 INFO - 'MAIL': '/var/mail/cltbld', 12:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:10 INFO - 'MOZ_NO_REMOTE': '1', 12:32:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:10 INFO - 'NO_EM_RESTART': '1', 12:32:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:10 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:10 INFO - 'SHELL': '/bin/bash', 12:32:10 INFO - 'SHLVL': '1', 12:32:10 INFO - 'TERM': 'linux', 12:32:10 INFO - 'TMOUT': '86400', 12:32:10 INFO - 'USER': 'cltbld', 12:32:10 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:10 INFO - '_': '/tools/buildbot/bin/python'} 12:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32:11 INFO - Downloading blobuploader-1.2.4.tar.gz 12:32:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:32:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:32:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:32:11 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 12:32:11 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 12:32:11 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 12:32:11 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 12:32:11 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 12:32:11 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 12:32:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:32:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 12:32:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:12 INFO - Downloading docopt-0.6.1.tar.gz 12:32:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:32:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 12:32:12 INFO - Installing collected packages: blobuploader, requests, docopt 12:32:12 INFO - Running setup.py install for blobuploader 12:32:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:32:12 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 12:32:12 INFO - Running setup.py install for requests 12:32:13 INFO - Running setup.py install for docopt 12:32:13 INFO - Successfully installed blobuploader requests docopt 12:32:13 INFO - Cleaning up... 12:32:13 INFO - Return code: 0 12:32:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:13 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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32:13 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 12:32:13 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 12:32:13 INFO - Using env: {'DISPLAY': ':0', 12:32:13 INFO - 'HOME': '/home/cltbld', 12:32:13 INFO - 'LANG': 'en_US.UTF-8', 12:32:13 INFO - 'LANGUAGE': 'en_US:en', 12:32:13 INFO - 'LOGNAME': 'cltbld', 12:32:13 INFO - 'MAIL': '/var/mail/cltbld', 12:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:13 INFO - 'MOZ_NO_REMOTE': '1', 12:32:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:13 INFO - 'NO_EM_RESTART': '1', 12:32:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:13 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:13 INFO - 'SHELL': '/bin/bash', 12:32:13 INFO - 'SHLVL': '1', 12:32:13 INFO - 'TERM': 'linux', 12:32:13 INFO - 'TMOUT': '86400', 12:32:13 INFO - 'USER': 'cltbld', 12:32:13 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:13 INFO - '_': '/tools/buildbot/bin/python'} 12:32:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:32:13 INFO - Running setup.py (path:/tmp/pip-6YEdZ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:32:13 INFO - Running setup.py (path:/tmp/pip-g0AYAm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:32:13 INFO - Running setup.py (path:/tmp/pip-FATNm1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:32:13 INFO - Running setup.py (path:/tmp/pip-AyZRgs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 12:32:13 INFO - Running setup.py (path:/tmp/pip-5IYKgR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:32:13 INFO - Running setup.py (path:/tmp/pip-A1hvTE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:32:13 INFO - Running setup.py (path:/tmp/pip-OXShGB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:32:13 INFO - Running setup.py (path:/tmp/pip-YVAxjY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:32:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 12:32:13 INFO - Running setup.py (path:/tmp/pip-Oqq_YY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 12:32:14 INFO - Running setup.py (path:/tmp/pip-XEP0WO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:32:14 INFO - Running setup.py (path:/tmp/pip-gvLmuE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:32:14 INFO - Running setup.py (path:/tmp/pip-g_1NXp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:32:14 INFO - Running setup.py (path:/tmp/pip-W4Rpdq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:32:14 INFO - Running setup.py (path:/tmp/pip-vsly98-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:32:14 INFO - Running setup.py (path:/tmp/pip-16tTyo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 12:32:14 INFO - Running setup.py (path:/tmp/pip-OG30O2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 12:32:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 12:32:14 INFO - Running setup.py (path:/tmp/pip-tTPxWT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 12:32:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:32:14 INFO - Running setup.py install for manifestparser 12:32:14 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 12:32:14 INFO - Running setup.py install for mozcrash 12:32:14 INFO - Running setup.py install for mozdebug 12:32:14 INFO - Running setup.py install for mozdevice 12:32:15 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for mozfile 12:32:15 INFO - Running setup.py install for mozhttpd 12:32:15 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for mozinfo 12:32:15 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for mozInstall 12:32:15 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for mozleak 12:32:15 INFO - Running setup.py install for mozlog 12:32:15 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for moznetwork 12:32:15 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for mozprocess 12:32:15 INFO - Running setup.py install for mozprofile 12:32:15 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 12:32:15 INFO - Running setup.py install for mozrunner 12:32:16 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 12:32:16 INFO - Running setup.py install for mozscreenshot 12:32:16 INFO - Running setup.py install for moztest 12:32:16 INFO - Running setup.py install for mozversion 12:32:16 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 12:32:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:32:16 INFO - Cleaning up... 12:32:16 INFO - Return code: 0 12:32:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:32:16 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')]} 12:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:16 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')]} 12:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:16 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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32:16 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 12:32:16 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 12:32:16 INFO - Using env: {'DISPLAY': ':0', 12:32:16 INFO - 'HOME': '/home/cltbld', 12:32:16 INFO - 'LANG': 'en_US.UTF-8', 12:32:16 INFO - 'LANGUAGE': 'en_US:en', 12:32:16 INFO - 'LOGNAME': 'cltbld', 12:32:16 INFO - 'MAIL': '/var/mail/cltbld', 12:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:16 INFO - 'MOZ_NO_REMOTE': '1', 12:32:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:16 INFO - 'NO_EM_RESTART': '1', 12:32:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:16 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:16 INFO - 'SHELL': '/bin/bash', 12:32:16 INFO - 'SHLVL': '1', 12:32:16 INFO - 'TERM': 'linux', 12:32:16 INFO - 'TMOUT': '86400', 12:32:16 INFO - 'USER': 'cltbld', 12:32:16 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:16 INFO - '_': '/tools/buildbot/bin/python'} 12:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:32:16 INFO - Running setup.py (path:/tmp/pip-Hn2VGi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:32:16 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)) 12:32:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:32:16 INFO - Running setup.py (path:/tmp/pip-FL619b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:32:16 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)) 12:32:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:32:16 INFO - Running setup.py (path:/tmp/pip-LnaKXH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:32:16 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)) 12:32:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:32:16 INFO - Running setup.py (path:/tmp/pip-uaDO6f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:32:16 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)) 12:32:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 12:32:16 INFO - Running setup.py (path:/tmp/pip-2v2g3X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 12:32:16 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)) 12:32:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:32:16 INFO - Running setup.py (path:/tmp/pip-LhfhMm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:32:17 INFO - Running setup.py (path:/tmp/pip-rSoFgi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:32:17 INFO - Running setup.py (path:/tmp/pip-kw4_Wk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 12:32:17 INFO - Running setup.py (path:/tmp/pip-9f91l_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 12:32:17 INFO - Running setup.py (path:/tmp/pip-0GQiko-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 12:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:32:17 INFO - Running setup.py (path:/tmp/pip-Nu40ap-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:32:17 INFO - Running setup.py (path:/tmp/pip-PIeMYc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:32:17 INFO - Running setup.py (path:/tmp/pip-UURuOZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:32:17 INFO - Running setup.py (path:/tmp/pip-PD2doX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:32:17 INFO - Running setup.py (path:/tmp/pip-vHidv_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 12:32:17 INFO - Running setup.py (path:/tmp/pip-C2DslL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 12:32:17 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)) 12:32:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 12:32:17 INFO - Running setup.py (path:/tmp/pip-W6P4Xj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 12:32:17 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)) 12:32:17 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)) 12:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 12:32:17 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)) 12:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 12:32:17 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)) 12:32:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32:19 INFO - Downloading blessings-1.5.1.tar.gz 12:32:19 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 12:32:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 12:32:19 INFO - Installing collected packages: blessings 12:32:19 INFO - Running setup.py install for blessings 12:32:19 INFO - Successfully installed blessings 12:32:19 INFO - Cleaning up... 12:32:19 INFO - Return code: 0 12:32:19 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 12:32:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 12:32:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 12:32:19 INFO - Reading from file tmpfile_stdout 12:32:19 INFO - Current package versions: 12:32:19 INFO - argparse == 1.2.1 12:32:19 INFO - blessings == 1.5.1 12:32:19 INFO - blobuploader == 1.2.4 12:32:19 INFO - docopt == 0.6.1 12:32:19 INFO - manifestparser == 1.1 12:32:19 INFO - mozInstall == 1.12 12:32:19 INFO - mozcrash == 0.16 12:32:19 INFO - mozdebug == 0.1 12:32:19 INFO - mozdevice == 0.47 12:32:19 INFO - mozfile == 1.2 12:32:19 INFO - mozhttpd == 0.7 12:32:19 INFO - mozinfo == 0.9 12:32:19 INFO - mozleak == 0.1 12:32:19 INFO - mozlog == 3.0 12:32:19 INFO - moznetwork == 0.27 12:32:19 INFO - mozprocess == 0.22 12:32:19 INFO - mozprofile == 0.27 12:32:19 INFO - mozrunner == 6.11 12:32:19 INFO - mozscreenshot == 0.1 12:32:19 INFO - mozsystemmonitor == 0.0 12:32:19 INFO - moztest == 0.7 12:32:19 INFO - mozversion == 1.4 12:32:19 INFO - psutil == 3.1.1 12:32:19 INFO - requests == 1.2.3 12:32:19 INFO - wsgiref == 0.1.2 12:32:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:32:19 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')]} 12:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:19 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')]} 12:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:19 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 0x7fc443663c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c96c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x167b7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, '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': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', '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 12:32:19 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 12:32:19 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 12:32:19 INFO - Using env: {'DISPLAY': ':0', 12:32:19 INFO - 'HOME': '/home/cltbld', 12:32:19 INFO - 'LANG': 'en_US.UTF-8', 12:32:19 INFO - 'LANGUAGE': 'en_US:en', 12:32:19 INFO - 'LOGNAME': 'cltbld', 12:32:19 INFO - 'MAIL': '/var/mail/cltbld', 12:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:19 INFO - 'MOZ_NO_REMOTE': '1', 12:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:19 INFO - 'NO_EM_RESTART': '1', 12:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:19 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:19 INFO - 'SHELL': '/bin/bash', 12:32:19 INFO - 'SHLVL': '1', 12:32:19 INFO - 'TERM': 'linux', 12:32:19 INFO - 'TMOUT': '86400', 12:32:19 INFO - 'USER': 'cltbld', 12:32:19 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:19 INFO - '_': '/tools/buildbot/bin/python'} 12:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 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)) 12:32:20 INFO - Cleaning up... 12:32:20 INFO - Return code: 0 12:32:20 INFO - Running post-action listener: _resource_record_post_action 12:32:20 INFO - Running post-action listener: _start_resource_monitoring 12:32:20 INFO - Starting resource monitoring. 12:32:20 INFO - ##### 12:32:20 INFO - ##### Running install step. 12:32:20 INFO - ##### 12:32:20 INFO - Running pre-action listener: _resource_record_pre_action 12:32:20 INFO - Running main action method: install 12:32:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 12:32:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 12:32:20 INFO - Reading from file tmpfile_stdout 12:32:20 INFO - Detecting whether we're running mozinstall >=1.0... 12:32:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 12:32:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 12:32:20 INFO - Reading from file tmpfile_stdout 12:32:20 INFO - Output received: 12:32:20 INFO - Usage: mozinstall [options] installer 12:32:20 INFO - Options: 12:32:20 INFO - -h, --help show this help message and exit 12:32:20 INFO - -d DEST, --destination=DEST 12:32:20 INFO - Directory to install application into. [default: 12:32:20 INFO - "/builds/slave/test-pgo"] 12:32:20 INFO - --app=APP Application being installed. [default: firefox] 12:32:20 INFO - mkdir: /builds/slave/test-pgo/build/application 12:32:20 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'] 12:32:20 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 12:32:34 INFO - Reading from file tmpfile_stdout 12:32:34 INFO - Output received: 12:32:34 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 12:32:34 INFO - Running post-action listener: _resource_record_post_action 12:32:34 INFO - ##### 12:32:34 INFO - ##### Running run-tests step. 12:32:34 INFO - ##### 12:32:34 INFO - Running pre-action listener: _resource_record_pre_action 12:32:34 INFO - Running main action method: run_tests 12:32:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 12:32:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 12:32:34 INFO - Python 2.7.3 12:32:34 INFO - Return code: 0 12:32:34 INFO - grabbing minidump binary from tooltool 12:32:34 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')]} 12:32:34 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 0x16bfd70>, '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 0x16c0b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 12:32:34 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 12:32:34 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 12:32:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:32:34 INFO - Return code: 0 12:32:34 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 12:32:35 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 12:32:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 12:32:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 12:32:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 12:32:35 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/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 12:32:35 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/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost 12:32:35 INFO - Using env: {'DISPLAY': ':0', 12:32:35 INFO - 'HOME': '/home/cltbld', 12:32:35 INFO - 'LANG': 'en_US.UTF-8', 12:32:35 INFO - 'LANGUAGE': 'en_US:en', 12:32:35 INFO - 'LOGNAME': 'cltbld', 12:32:35 INFO - 'MAIL': '/var/mail/cltbld', 12:32:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 12:32:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 12:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:35 INFO - 'MOZ_NO_REMOTE': '1', 12:32:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 12:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:35 INFO - 'NO_EM_RESTART': '1', 12:32:35 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:32:35 INFO - 'PWD': '/builds/slave/test-pgo', 12:32:35 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 12:32:35 INFO - 'SHELL': '/bin/bash', 12:32:35 INFO - 'SHLVL': '1', 12:32:35 INFO - 'TERM': 'linux', 12:32:35 INFO - 'TMOUT': '86400', 12:32:35 INFO - 'USER': 'cltbld', 12:32:35 INFO - 'XDG_SESSION_COOKIE': '9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918', 12:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:35 INFO - '_': '/tools/buildbot/bin/python'} 12:32:35 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/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost'] with output_timeout 3600 12:32:35 INFO - mozversion INFO | application_buildid: 20151118103030 12:32:35 INFO - mozversion INFO | application_changeset: 4a9ebc74d62a0b07a026a54631b15c21660a3933 12:32:35 INFO - mozversion INFO | application_display_name: Nightly 12:32:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:32:35 INFO - mozversion INFO | application_name: Firefox 12:32:35 INFO - mozversion INFO | application_remotingname: firefox 12:32:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:32:35 INFO - mozversion INFO | application_vendor: Mozilla 12:32:35 INFO - mozversion INFO | application_version: 45.0a1 12:32:35 INFO - mozversion INFO | platform_buildid: 20151118103030 12:32:35 INFO - mozversion INFO | platform_changeset: 4a9ebc74d62a0b07a026a54631b15c21660a3933 12:32:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:32:35 INFO - mozversion INFO | platform_version: 45.0a1 12:32:35 INFO - 2015-11-18 12:32:35,262 DEBUG : using testdate: 1447878755 12:32:35 INFO - 2015-11-18 12:32:35,262 DEBUG : actual date: 1447878755 12:32:35 INFO - 2015-11-18 12:32:35,270 INFO : starting webserver on 'localhost:42284' 12:32:35 INFO - 2015-11-18 12:32:35,270 INFO : Starting test suite talos-linux64-ix-036.e 12:32:35 INFO - 2015-11-18 12:32:35,271 INFO : Starting test dromaeo_css 12:32:35 INFO - 2015-11-18 12:32:35,271 DEBUG : operating with platform_type : linux_ 12:32:35 INFO - 2015-11-18 12:32:35,271 INFO : Initialising browser for dromaeo_css test... 12:32:35 INFO - 2015-11-18 12:32:35,289 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp38mjxA/profile http://localhost:42284/getInfo.html 12:32:41 INFO - 2015-11-18 12:32:41,609 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:32:41 INFO - 2015-11-18 12:32:41,609 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:32:41 INFO - 2015-11-18 12:32:41,609 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:32:41 INFO - 2015-11-18 12:32:41,609 DEBUG : BROWSER_OUTPUT: __metrics 12:32:42 INFO - 2015-11-18 12:32:42,614 INFO : Browser initialized. 12:32:42 INFO - 2015-11-18 12:32:42,614 INFO : Running cycle 1/1 for dromaeo_css test... 12:32:42 INFO - 2015-11-18 12:32:42,614 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp38mjxA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:32:54 INFO - 2015-11-18 12:32:54,326 DEBUG : BROWSER_OUTPUT: RSS: Main: 141496320 12:32:54 INFO - 2015-11-18 12:32:54,326 DEBUG : BROWSER_OUTPUT: 12:33:43 INFO - 2015-11-18 12:33:43,935 DEBUG : BROWSER_OUTPUT: 1447878823934 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] 12:35:52 INFO - 2015-11-18 12:35:52,465 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42284/tests/dromaeo/cssquery-ext.html) 12:35:53 INFO - 2015-11-18 12:35:53,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 147615744 12:35:53 INFO - 2015-11-18 12:35:53,632 DEBUG : BROWSER_OUTPUT: 12:38:55 INFO - 2015-11-18 12:38:55,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-ext.html (next: http://localhost:42284/tests/dromaeo/cssquery-jquery.html) 12:38:56 INFO - 2015-11-18 12:38:56,206 DEBUG : BROWSER_OUTPUT: RSS: Main: 141025280 12:38:56 INFO - 2015-11-18 12:38:56,206 DEBUG : BROWSER_OUTPUT: 12:41:57 INFO - 2015-11-18 12:41:57,931 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42284/tests/dromaeo/cssquery-mootools.html) 12:41:58 INFO - 2015-11-18 12:41:58,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 142573568 12:41:58 INFO - 2015-11-18 12:41:58,520 DEBUG : BROWSER_OUTPUT: 12:45:00 INFO - 2015-11-18 12:45:00,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42284/tests/dromaeo/cssquery-prototype.html) 12:45:00 INFO - 2015-11-18 12:45:00,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 140976128 12:45:00 INFO - 2015-11-18 12:45:00,744 DEBUG : BROWSER_OUTPUT: 12:48:03 INFO - 2015-11-18 12:48:03,050 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42284/tests/dromaeo/cssquery-yui.html) 12:48:03 INFO - 2015-11-18 12:48:03,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 142110720 12:48:03 INFO - 2015-11-18 12:48:03,632 DEBUG : BROWSER_OUTPUT: 12:51:05 INFO - 2015-11-18 12:51:05,999 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-yui.html (next: http://localhost:42284/tests/dromaeo/cssquery-dojo.html) 12:51:06 INFO - 2015-11-18 12:51:06,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 144490496 12:51:06 INFO - 2015-11-18 12:51:06,249 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,249 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:51:06 INFO - 2015-11-18 12:51:06,249 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:51:06 INFO - 2015-11-18 12:51:06,249 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:51:06 INFO - 2015-11-18 12:51:06,250 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:51:06 INFO - 2015-11-18 12:51:06,251 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3211.61460624725;3254.4955531892565;3273.9508907366394;3285.8357082145885;3306.768526203165;5175.301334319865;5327;5343.091674415349;5382.757775900084;5447.428020057891;5194.0910340690325;5227.294315267438;5286.947130528693;5287.259783630295;5382.569394448445;4933.40799104108;5030.270610761438;5077.416097148838;5089.643724939256;5117.437081920985;4986.875328116808;5053.949460505403;5067.619928505358;5073.923891141636;5122.487751224885;4780.139574876521;4836.105320515716;4879.780409881564;5004.624653151028;5023.2214006828635;4985.775640096168;4990.326305948687;4997.05056039355;5014.623903207274;5082.390113186441;4872.317875497433;4974.9751251243515;4992.276119962621;4993.051320249141;5054.216596427523;5220.3735551734035;5279.208118782213;5279.340082489689;5286.656367336111;5339.919901201485;5821.359650438449;5917.408259174092;5933.643981361089;5936.792212272593;5943.52451803859;5923.17075609415;5930.932432162209;5972.4027597240365;5973.223480947492;5978.521718262572;5375.596830237709;5445.319335083154;5451.618386712932;5526.392096869341;5593.216949627055;6007.639541627429;6052.6671033092725;6070.180525629072;6075.908861367083;6080.14877917092;5880.970595146997;5883.823485295448;5885.588008839426;5894.410558944071;5896.587238893322;5685.602007859493;5839.0949402842625;5854.7072646367515;5864.824055278348;5869.7945571904775;5773.913391299134;5790.565707571949;5844.970775146097;5857.20927674767;5877.030290002187;5834.329052159056;5839.941600584025;5894.8821023579285;5944.910826337608;5975.4024597540765;4358.0412309291905;4377.124575084932;4470.932936005963;4520.751358675238;4542.636589072932;3982.820773065219;3992.3612222044912;4005.1388951375593;4012.6990475714438;4028.576999415019;5573.693446860461;5588.6646801192055;5600.439956004367;5632.549396048306;5645.435456454322;5721.656700597978;5874.794382196603;5879.823605291848;5894.8821023579285;5907.527397808165;5589.720513974285;5688.914666280009;5702.8289151325525;5705.486506214461;5712.171735098387;5098.08234517783;5108;5109.4379618241965;5127.154019586801;5130.153524668388;5778.94221057784;5819.952408566414;5865.472107510259;5933.970330148321;6054;5890.882182356243;5943.554233432424;5945.048792193228;5962.046072628359;5967.075103358986;5985.341612422717;6066.1507388966465;6067.696615169312;6079.787207447718;6087.086936959315;5629.0712032514175;5697.487226149667;5768.1636162757;5848.2689663792025;5901.881962360814;5861.325947515919;5914.763409463573;5937.376575459514;5948.196993405835;5959.91060134107;5797.797077102281;5833.4749872511675;5842.298924129132;5845.298564172326;5845.561582881386;5691.060974939089;5731.570132240182;5821.650700957956;5865.530757539302;5906.852328691645;5673;5679.091345384719;5688;5688.459596338341;5689.630174038674;5638.464345887298;5743.282089738783;5803.941960580509;5824.854378640568;5831.591788574759;5621.353544342453;5675.77296908119;5681.460261275172;5695.829125126253;5704.087346024616 12:51:06 INFO - 2015-11-18 12:51:06,252 DEBUG : BROWSER_OUTPUT: |1;ext.html;550.4346276830172;562.361719448426;596.5317225977608;598.6617561077992;605.7970579855748;31505.21236969074;32405.51391729126;39755.80122099389;40084.19410641249;40183;43163;43718;43805.78097109518;43940.560594394046;44115.55884441155;8754.255888249507;8879.689210877621;8891.510966896818;8895.28837692983;8913.1978121969;8648.048714641385;8728.127187281252;8765.430247033953;8769.429987050853;8771.956140219321;8718.389712720113;8768.210861022506;8808.559572021406;8815.294776417903;8826.426282291663;8698.782530436727;8780.780480488007;8781.692640757576;8791.736247912573;8799.912000879973;8576.957115214384;8697.565121743888;8763.342749293828;8779.736607901774;8781.7804554886;39835.40246896299;40193.39709904354;40413.79793101045;40524;40709.185816283505;3143.5913331267016;3154.3218208085373;3199.9200019999453;3299.835008249578;3518.4194607889667;29219.41561168786;29541.113766587037;30292.8485357574;30335;30770.23074423135;3305.8347082645773;3344.832758362072;3406;3409.9488507672404;3452.413089774719;227984;228523;230029;231536;231959;40873.795631021654;41038.179236415104;41641.58358416438;55372.72313638487;55939.44060559342;40932.181356372705;40952;41158.176836463106;41247.587524124974;41718;14608.780868286984;14790.260486975609;14792.482263120739;14799.112053276838;14800.555983320517;10729.624463143753;10780.353178809297;10800.351978881292;10832.350058996328;10886.40124793144;13931.860681393258;13935.233562153977;13951.232682202373;14006.929965350108;14161.929190353982;5950.970245148833;5963.314218864886;5983.649156472389;6082;6104.847378815565;929.159111004542;952.8613307097951;1011.3830563356347;1035.5081336365167;1121.489722176424;29000;29115.272118197216;29578.40843183124;29817.552736708967;29842;1003.1673710820002;1016.3952448293254;1089.803835309636;1094.1684319916922;1106.9096939514732;15039.924800375928;15090.320935557926;15102.848971510364;15162.924185379223;15175;311487;313299;315291;316751;317359;51685;52156.47843521592;52259.47740522546;52304.95390092177;52370;1263.2799304396406;1271.9173253738477;1292;1302.5050480817229;1306.4186437035428;28322.433551328446;28717.28206794847;29065.564016540193;29120;29488.262793430335;45864.31203531883;46114.769426152656;46365.768171158925;46404.07191856077;46470.53529464662;281074;288090;289054;290662;292597;32690.182745431553;41199.1760164801;42195.36706949337;42319.518816841264;42895.7855210722;42050.78974605107;43336.133277334906;43360.87744112732;44136.77931610322;44469.77765111283;36834;36848.07879802919;37775.81112094422;38402.807985959895;38542;36442.27115457729;36547.634523655484;37275.69535066368;37518.24963500769;37684.62315376811;36292.274154516235;36874;37135;37186.442203367515;37308.6269137316;32518;33606.49590256197;33790.66209337875;34487.827560863036;35103.47344789779;25842.449453032877;26029.739702602732;27197.728022720312;28236;28611.284717881386 12:51:06 INFO - 2015-11-18 12:51:06,256 DEBUG : BROWSER_OUTPUT: |2;jquery.html;349.7918738350682;358.70406914295705;360.1213040181954;362.2519497238203;368.10550362618824;12613.117081804277;13022.93488532556;16979.915100424496;18028.18873150708;19003.714944275813;12010.0992425568;12048.518059277618;12212.32832194229;12216.938915305433;12239.877601223985;4473.105378924212;4578.542145785428;4583.335416364625;4594.379758732562;4594.632429405639;4767.523247675239;4893.241547560134;4898.804047838083;4903.411590609114;4905.583025442842;4688.742119183444;4806.7356295403915;4812.711237325754;4843;4850.830220942268;4758.952410475886;4777.235642297216;4778.331033655309;4850.393700787402;4871.26930960357;4125.443065186191;4142.834286628531;4149.605787450188;4203.390508376268;4334.024844410014;13556.186628802303;13571.45714171432;13826.723465530733;13828.308584570732;14310.427582896671;2060.3406909788873;2062.3400511836217;2077;2144.8927553622257;2196.4399078235106;9738.75653108678;9839.655612053615;9881.555330010095;9964.551595178234;10251.026152515498;1681.9747703784453;1691.7171576363748;1693.7798086248831;1728.2222999650207;1784.0366202250768;59765;60959.085613715826;62757.37242627561;63419;64413.355866441205;7053.68258428372;7165.211826699059;7217.8556428871125;7244.565326080452;7253.202147763742;8443.66225350979;8567.871481927776;8605.13948605147;8607.139286071468;8611.354148438892;5653.208550802891;5762;5781.4218578141845;5809.970950145223;5822.94177058224;5882.294124704978;6039.124326972565;6052.6065805723365;6065.787697430657;6109.175261339751;3280.2455435250026;3315.2540678347177;3331.950020749691;3351.0952042948757;3352.430086885235;1156.2888823373733;1166.160399427397;1174.1663418972626;1174.442139983501;1221.8533775946944;408.8344220590665;419.77961570175904;420.43871431639104;454.3162540376712;470.0809916613082;10388.324758890649;10437.68686939393;10475.214358923111;10731.141508679288;10760.085392741548;435.4339358833504;435.76452797150415;437.90803931174173;447.0611715397704;448.614191795056;5621.747021384048;5839.824805255849;5861.648301101947;5974.4622983930785;5989.700514974234;501671;503616;513693;515965;530266;76144.61927690326;76334.618326908;76744.23255767483;77617.6119119412;77645.61177194078;492.22720329082995;493.45226798253907;494.1574615281009;494.675987228366;495.60846930924885;10164.237682174014;10305.072543471124;10306.536205870603;10318.587256509956;10348.948255258676;34537.48193777045;34839.47740783839;35642.46536302009;35982;36500.81749591341;47493;48420.273695895296;48463.75768121278;48897.75551122222;49574.75212624058;6888.827779305558;6899.93100069013;6912.758053468105;6928.792136235921;6945.687444064928;12519;12651.11442199074;12704;12825.038122141063;12898.548550801052;10662.306950048222;11201.943990279997;11239.494222760159;11267.887321126684;11328.546858125583;10140.340877842917;10594.735131621463;10683.358998460117;10756.193285503776;10823.891761082288;10916.12670986335;11039;11128;11229.49467273991;11297.774044519228;6603.834904127243;6983.022376867344;6996.685149168198;7041.014258003986;7096.290370963069;10814.945925270324;10989.725256868642;11122.944385278022;11182.440877955907;11409.942950285196 12:51:06 INFO - 2015-11-18 12:51:06,257 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1475.4172102019695;1487.583476626545;1616.862566681832;1705.9317627294906;1763.06557524512;6625.271220165778;6657.90013149802;7730.11103723072;7952.801179970496;7955.761327160188;7252.492325537214;7377.926220737791;7422.331990120888;7449;7456.70173193073;2211.6903633491324;2245.5845668551287;2250.4036430345973;2263.1286954522543;2289.988550057256;2257.616205245104;2327.7904988551027;2333.1717240379676;2383.380321116505;2397.7482364351754;2288.6338185890263;2288.9771102288933;2321.2223904991765;2332.8133749300014;2352.5882970480184;2310.780475854791;2316.2356422380576;2343.742188359279;2344.367020904346;2355;2299.8160147188187;2323.5585238804574;2331.6269396896414;2335.8832058397184;2339.520398318358;7375.557466551971;7432.739854105081;7463.141738700065;7519.812004699872;7546.169921308651;4671.766411679437;4761.785719642625;4780.163471392492;4788.904221915577;4803.543663351976;3047.4514587374265;3080.445519806434;3087.6294844618787;3109.937801243985;3159.162821852211;1052.5684469367523;1067.3809190669392;1071.196602548089;1091.3942761767216;1116.7096554895704;10927.890721092766;10940.617078402218;10943.56225750969;10965.945170274177;10999.780004399867;4341.804618792162;4349.652027837766;4411.228035093845;4428.31361139024;4445.355423463644;9244.90755092454;9276.11876871696;9315.115064068903;9318.207952324128;9322.30082743797;4685.718856868599;4696.976515117402;4697.271922851895;4698.496481126075;4719.5752382285755;3791.829367678461;3869.5163104611925;4245.830166793294;4260.510041345223;4288.378185163197;3639.1811842335264;3692.1508053147927;3705.184859330997;3720.3489389357296;3735.084904198489;1211.3277131192096;1219.4146809531474;1223.6757259326287;1235.7466719322517;1236.4436003798328;1303.5567906911506;1311.8294621699117;1316.6839958409912;1320.0495643136926;1326.694860182144;7150;7243.130824301117;7253.455990800711;7266.128064632172;7317.195108538057;491.8081948040267;501.6237821633775;514.6371807875439;515.8787684894047;521.3274875410677;5723.341815691166;5751.424857514216;5757.596968212269;5763.769449222068;5836.708164591839;8964.14840590143;9267.814643707088;9295.11696388842;9385.108414700593;9494.525273736286;9259.981402045769;9309.581068851918;9391.154796068387;9447.480388578695;9474.336796424184;7044.682989265495;7129.251428600026;7140.750073747394;7306.525075870051;7307.378872795872;6880.208775990825;6961.965190174219;7026.262242464672;7056.364927156581;7067.929320706727;8028.263324638491;8162.959185204036;8356.331493480386;8362.372822038249;8388.328933685412;8499.022612399405;8511.489310641382;8626.39615226928;8626.611802468778;8627.568621569022;6337.366263373626;6783.94848798437;6817.284185160487;6823.55646882951;7073.328033836778;7291.453141014551;7491.737789177353;7506.924930750841;7542.396608271435;7559.055118110236;6877.449804015567;7012.474064445289;7100.609466479186;7124.145102587722;7158.785236442915;7084.929150708633;7112.466565007541;7115.181754098138;7208.315210055036;7211.67547460355;6807.727690892507;7058.54119482232;7060.082189315356;7072.292770722886;7101.964490177516;6446.129772480655;6557.803265902031;6572.277049524644;6576.210854697467;6597.967010164918;6606.900896486461;6670.232923213893;6693.397594216543;6696;6697.531172817856 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: |4;prototype.html;344.204886711696;348.7576134586463;349.7953697087204;350.5652990292037;353.2793102071772;6274.874502509947;6278.968605156968;6332.62004279743;6365.331640177784;6470.450011749007;6303.621782693031;6316.621002739827;6339.334369891164;6343.6193828370215;6376.904346434807;4825.806967721287;4832.975835120819;4835.202191638376;4837.201861692816;4842.1042107210105;4839.128956787777;4847.351900353879;4851.345068415754;4853.757312134397;4867.707937523742;4666.253399456088;4788.61691064714;4789;4791.041791641685;4794.208955522335;4782.856514304559;4784.5933095686905;4787.305840653085;4789.568938795508;4798.23228283473;4147.668186545069;4154.439150714645;4158.500979882433;4161.146964872194;4163.75017498948;5925.318588362307;5955.464008239279;6009.369016253274;6029.668368239745;6057.212562366908;5425.430329815392;5505.119180931072;5521.585881058937;5525.060739674265;5533.3083364579425;6564.785514679799;6673.299303573152;6686.6656667166935;6693.062971184038;6694.933050669529;4545.272756358967;4546.249868771649;4568.132054909556;4573.702709323917;4600.5169457207185;6880.071190389283;6932.792016239521;7011.474139439511;7015.8596828063155;7021.754238601625;4642.605378542794;4650.093231819763;4691.366665500182;4709.128811169876;4713.269443236304;4756.239001759702;4764.094821983812;4765.571098601074;4768.737719425396;4772.260299653559;5546.528545073714;5566.526845218121;5567.387587365387;5568.860778480571;5578.972105139448;6673.4661227101715;6806.149231346082;6808.285130061371;6820.658967051628;6829.487788415889;1646.1193261604983;1669.0736641164272;1687.8480936715755;1689.6536210076904;1690.0366790929297;1113.4265853085667;1120.9327440353604;1127.761393633653;1132.0321125437797;1142.3146112332533;6432;6452.774152904626;6454.451371633464;6456.192975878015;6470.54706170573;6707.027481015225;6765.255821859591;6800;6834.6582670866255;6840.31596840322;6348.037996717616;6394.776182833579;6435.485161187094;6438.8390290243115;6450.774222902176;3759.8684046058256;3765.981170094132;3769.6418840210135;3775.3959366501226;3787.791671458096;7073.363397294268;7110.928890711027;7118;7121.074260346122;7132.429405647639;6946.305369463013;7050.647467626598;7058.647067646597;7072.469564782662;7087.89368159478;7057.505974581833;7069.434445244368;7087.625645408846;7113.964430177816;7122.928770712227;6850.554713943578;6874.896876546956;6896.4827637926355;6903;6912.963055541709;6550.312217217123;6744.62904540255;6750.6624668767345;6797.422219111233;6816.795496135124;7062.329078737512;7100.751473698396;7103.2541583133025;7103.467239957127;7112.573245605074;4669.626429885669;4680.3915490985955;4683.320918466871;4688.671792974459;4689.733771881641;6822.931770682429;6857;6862.073620061427;6903.309669033258;7083.185433675193;6844.794656160323;6858.657067146523;6863;6865.519413640997;6876.518643694893;6845.041694162921;6849.589024658537;6853.383195512428;6855.868781651171;6866.450683945172;6539.21529416454;6579.078928950046;6592.17597800275;6620.271770105188;6696.19645642526;6692.196936367667;6775.186977562724;6823.918704794886;6826.214985276558;6833;6584.045313429621;6619.073329733745;6633.1045308882685;6634.635095069818;6658.600483970978 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: |5;yui.html;720.456055677963;831.8918540589723;834.3075247544539;850.1158794853353;887.1217494680265;13162.21026738395;13457;13507.324633768298;13554.796678049825;13607.659808504792;12936.223826570413;13119.606411807637;13138.540151094714;14379.568612941603;14765.557033288991;790.881367794831;807.4428644235459;808.5391326943644;808.5876203136384;811.0348685064783;816.6243527977138;818.382121498268;820.8153165537766;823.8558252305852;824.2746383182821;854.086127843208;864.9048604653483;865.8917635295588;866.6273502393968;868.1231955724701;852.7186028610544;857.8927634045745;858.0304256190497;858.5492616376453;860.9741707748809;620.9161763162006;622.8318354044428;624.4972796898495;626.3048016701458;627.3036929008797;13694.65763355924;13795.379207935566;13840.377183026789;13861.37623806921;13885.861141388557;10157;10160.79678406435;10304.227182961233;10482.68551943443;10953.83569246462;10084.848727269024;10163.695089147337;10185.13426358753;10230.283880128394;10235.488225588691;3185.904422867318;3228.1929517620597;3268.542404063433;3277.885274015422;3299.4390953537954;46004.53995460036;46420.53579464196;46525.30212046822;46633.5336646636;46776.7661161692;7504.249575042452;7554.206808285161;7615.238476152451;7702.691892324245;7759.456838021284;11714.94142529299;11795.941020294844;11861.5848445304;11864.16950813452;11888.881111188777;6721.529492935545;6765.458763298924;6817;6828.453723702092;6982.371586557234;4250.787460626956;4284.635805956466;4313.245182093121;4336.284513055373;4352.173087113439;2727.0727952496245;2736.87684054218;2740.082072505733;2768.321761168527;2786.2616406652255;454.85603706677733;463.25878594248934;472.230264668588;476.4425622022173;477.191160982132;517.428241792821;581.4011568084879;581.5492992930428;677.6510097299895;687.0153111950666;8713.651453941771;8942;9056.818863622691;9230.307726920508;9805.411675299505;586.9501092407107;686.0566720758957;693.3032302535919;705.947053970944;711.989320160198;4152.501699796044;4447.177272204653;4450.777461126931;4548.408706868086;4589.187271027925;57398.71300643554;57729.42270577324;57852.4214757847;57942.71028644914;58263.70868145632;52246.47753522492;52748.20877686761;53027.469725303024;53368.73315633475;55760;45877.31184032242;46650;46926;48375.758121209874;49205;8840.24857887061;8864.601092950703;9016.368854180408;9031.683891063782;9144.451332920045;36605.450918235714;36802.447963280036;37482;37973.77222936082;38068.42897356597;42775.35836962386;46087;47055.76472117617;47107.76446117885;47548.2867756977;11648.09413407145;11858.762824743284;11903.40482975865;11941.16411851162;11977.700557486132;12414.751704966031;12455.501779928702;12464.43910024033;12747.80878286845;12876.420561074961;11924.523019079139;11959.820602690792;12122.696932576757;12286.631401057983;12456.875431245571;11573;11811.704707382385;11835.171537992257;11882.227655202385;12236.449359778653;11413.265764193091;11462.083033357145;11930.940345298219;11935.164538482222;12065.443748069287;10224.079832815083;10296.227782916161;10522.947385263025;10710.11416898206;10799.645619519037;9733.56198971062;10357;10395;10564.577416903237;10626.946865265934 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,301 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: __startTimestamp1447879866247__endTimestamp 12:51:06 INFO - 2015-11-18 12:51:06,259 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,260 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:51:06 INFO - 2015-11-18 12:51:06,260 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:51:06 INFO - 2015-11-18 12:51:06,260 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,260 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5456.61 Median:5689.63 stddev:617.86 (10.9%) stddev-sans-first:594.13 12:51:06 INFO - 2015-11-18 12:51:06,261 DEBUG : BROWSER_OUTPUT: Values: 3211.6 3254.5 3274.0 3285.8 3306.8 5175.3 5327.0 5343.1 5382.8 5447.4 5194.1 5227.3 5286.9 5287.3 5382.6 4933.4 5030.3 5077.4 5089.6 5117.4 4986.9 5053.9 5067.6 5073.9 5122.5 4780.1 4836.1 4879.8 5004.6 5023.2 4985.8 4990.3 4997.1 5014.6 5082.4 4872.3 4975.0 4992.3 4993.1 5054.2 5220.4 5279.2 5279.3 5286.7 5339.9 5821.4 5917.4 5933.6 5936.8 5943.5 5923.2 5930.9 5972.4 5973.2 5978.5 5375.6 5445.3 5451.6 5526.4 5593.2 6007.6 6052.7 6070.2 6075.9 6080.1 5881.0 5883.8 5885.6 5894.4 5896.6 5685.6 5839.1 5854.7 5864.8 5869.8 5773.9 5790.6 5845.0 5857.2 5877.0 5834.3 5839.9 5894.9 5944.9 5975.4 4358.0 4377.1 4470.9 4520.8 4542.6 3982.8 3992.4 4005.1 4012.7 4028.6 5573.7 5588.7 5600.4 5632.5 5645.4 5721.7 5874.8 5879.8 5894.9 5907.5 5589.7 5688.9 5702.8 5705.5 5712.2 5098.1 5108.0 5109.4 5127.2 5130.2 5778.9 5820.0 5865.5 5934.0 6054.0 5890.9 5943.6 5945.0 5962.0 5967.1 5985.3 6066.2 6067.7 6079.8 6087.1 5629.1 5697.5 5768.2 5848.3 5901.9 5861.3 5914.8 5937.4 5948.2 5959.9 5797.8 5833.5 5842.3 5845.3 5845.6 5691.1 5731.6 5821.7 5865.5 5906.9 5673.0 5679.1 5688.0 5688.5 5689.6 5638.5 5743.3 5803.9 5824.9 5831.6 5621.4 5675.8 5681.5 5695.8 5704.1 12:51:06 INFO - 2015-11-18 12:51:06,262 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,262 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44465.01 Median:36861.04 stddev:73062.06 (198.2%) stddev-sans-first:73192.62 12:51:06 INFO - 2015-11-18 12:51:06,263 DEBUG : BROWSER_OUTPUT: Values: 550.4 562.4 596.5 598.7 605.8 31505.2 32405.5 39755.8 40084.2 40183.0 43163.0 43718.0 43805.8 43940.6 44115.6 8754.3 8879.7 8891.5 8895.3 8913.2 8648.0 8728.1 8765.4 8769.4 8772.0 8718.4 8768.2 8808.6 8815.3 8826.4 8698.8 8780.8 8781.7 8791.7 8799.9 8577.0 8697.6 8763.3 8779.7 8781.8 39835.4 40193.4 40413.8 40524.0 40709.2 3143.6 3154.3 3199.9 3299.8 3518.4 29219.4 29541.1 30292.8 30335.0 30770.2 3305.8 3344.8 3406.0 3409.9 3452.4 227984.0 228523.0 230029.0 231536.0 231959.0 40873.8 41038.2 41641.6 55372.7 55939.4 40932.2 40952.0 41158.2 41247.6 41718.0 14608.8 14790.3 14792.5 14799.1 14800.6 10729.6 10780.4 10800.4 10832.4 10886.4 13931.9 13935.2 13951.2 14006.9 14161.9 5951.0 5963.3 5983.6 6082.0 6104.8 929.2 952.9 1011.4 1035.5 1121.5 29000.0 29115.3 29578.4 29817.6 29842.0 1003.2 1016.4 1089.8 1094.2 1106.9 15039.9 15090.3 15102.8 15162.9 15175.0 311487.0 313299.0 315291.0 316751.0 317359.0 51685.0 52156.5 52259.5 52305.0 52370.0 1263.3 1271.9 1292.0 1302.5 1306.4 28322.4 28717.3 29065.6 29120.0 29488.3 45864.3 46114.8 46365.8 46404.1 46470.5 281074.0 288090.0 289054.0 290662.0 292597.0 32690.2 41199.2 42195.4 42319.5 42895.8 42050.8 43336.1 43360.9 44136.8 44469.8 36834.0 36848.1 37775.8 38402.8 38542.0 36442.3 36547.6 37275.7 37518.2 37684.6 36292.3 36874.0 37135.0 37186.4 37308.6 32518.0 33606.5 33790.7 34487.8 35103.5 25842.4 26029.7 27197.7 28236.0 28611.3 12:51:06 INFO - 2015-11-18 12:51:06,263 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,264 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26283.23 Median:436.84 stddev:84211.11 (19277.5%) stddev-sans-first:84424.82 12:51:06 INFO - 2015-11-18 12:51:06,264 DEBUG : BROWSER_OUTPUT: Values: 349.8 358.7 360.1 362.3 368.1 12613.1 13022.9 16979.9 18028.2 19003.7 12010.1 12048.5 12212.3 12216.9 12239.9 4473.1 4578.5 4583.3 4594.4 4594.6 4767.5 4893.2 4898.8 4903.4 4905.6 4688.7 4806.7 4812.7 4843.0 4850.8 4759.0 4777.2 4778.3 4850.4 4871.3 4125.4 4142.8 4149.6 4203.4 4334.0 13556.2 13571.5 13826.7 13828.3 14310.4 2060.3 2062.3 2077.0 2144.9 2196.4 9738.8 9839.7 9881.6 9964.6 10251.0 1682.0 1691.7 1693.8 1728.2 1784.0 59765.0 60959.1 62757.4 63419.0 64413.4 7053.7 7165.2 7217.9 7244.6 7253.2 8443.7 8567.9 8605.1 8607.1 8611.4 5653.2 5762.0 5781.4 5810.0 5822.9 5882.3 6039.1 6052.6 6065.8 6109.2 3280.2 3315.3 3332.0 3351.1 3352.4 1156.3 1166.2 1174.2 1174.4 1221.9 408.8 419.8 420.4 454.3 470.1 10388.3 10437.7 10475.2 10731.1 10760.1 435.4 435.8 437.9 447.1 448.6 5621.7 5839.8 5861.6 5974.5 5989.7 501671.0 503616.0 513693.0 515965.0 530266.0 76144.6 76334.6 76744.2 77617.6 77645.6 492.2 493.5 494.2 494.7 495.6 10164.2 10305.1 10306.5 10318.6 10348.9 34537.5 34839.5 35642.5 35982.0 36500.8 47493.0 48420.3 48463.8 48897.8 49574.8 6888.8 6899.9 6912.8 6928.8 6945.7 12519.0 12651.1 12704.0 12825.0 12898.5 10662.3 11201.9 11239.5 11267.9 11328.5 10140.3 10594.7 10683.4 10756.2 10823.9 10916.1 11039.0 11128.0 11229.5 11297.8 6603.8 6983.0 6996.7 7041.0 7096.3 10814.9 10989.7 11122.9 11182.4 11409.9 12:51:06 INFO - 2015-11-18 12:51:06,264 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,264 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5442.45 Median:6391.75 stddev:2822.89 (44.2%) stddev-sans-first:2815.06 12:51:06 INFO - 2015-11-18 12:51:06,265 DEBUG : BROWSER_OUTPUT: Values: 1475.4 1487.6 1616.9 1705.9 1763.1 6625.3 6657.9 7730.1 7952.8 7955.8 7252.5 7377.9 7422.3 7449.0 7456.7 2211.7 2245.6 2250.4 2263.1 2290.0 2257.6 2327.8 2333.2 2383.4 2397.7 2288.6 2289.0 2321.2 2332.8 2352.6 2310.8 2316.2 2343.7 2344.4 2355.0 2299.8 2323.6 2331.6 2335.9 2339.5 7375.6 7432.7 7463.1 7519.8 7546.2 4671.8 4761.8 4780.2 4788.9 4803.5 3047.5 3080.4 3087.6 3109.9 3159.2 1052.6 1067.4 1071.2 1091.4 1116.7 10927.9 10940.6 10943.6 10965.9 10999.8 4341.8 4349.7 4411.2 4428.3 4445.4 9244.9 9276.1 9315.1 9318.2 9322.3 4685.7 4697.0 4697.3 4698.5 4719.6 3791.8 3869.5 4245.8 4260.5 4288.4 3639.2 3692.2 3705.2 3720.3 3735.1 1211.3 1219.4 1223.7 1235.7 1236.4 1303.6 1311.8 1316.7 1320.0 1326.7 7150.0 7243.1 7253.5 7266.1 7317.2 491.8 501.6 514.6 515.9 521.3 5723.3 5751.4 5757.6 5763.8 5836.7 8964.1 9267.8 9295.1 9385.1 9494.5 9260.0 9309.6 9391.2 9447.5 9474.3 7044.7 7129.3 7140.8 7306.5 7307.4 6880.2 6962.0 7026.3 7056.4 7067.9 8028.3 8163.0 8356.3 8362.4 8388.3 8499.0 8511.5 8626.4 8626.6 8627.6 6337.4 6783.9 6817.3 6823.6 7073.3 7291.5 7491.7 7506.9 7542.4 7559.1 6877.4 7012.5 7100.6 7124.1 7158.8 7084.9 7112.5 7115.2 7208.3 7211.7 6807.7 7058.5 7060.1 7072.3 7102.0 6446.1 6557.8 6572.3 6576.2 6598.0 6606.9 6670.2 6693.4 6696.0 6697.5 12:51:06 INFO - 2015-11-18 12:51:06,265 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,265 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5595.68 Median:6413.39 stddev:1696.23 (26.4%) stddev-sans-first:1654.55 12:51:06 INFO - 2015-11-18 12:51:06,266 DEBUG : BROWSER_OUTPUT: Values: 344.2 348.8 349.8 350.6 353.3 6274.9 6279.0 6332.6 6365.3 6470.5 6303.6 6316.6 6339.3 6343.6 6376.9 4825.8 4833.0 4835.2 4837.2 4842.1 4839.1 4847.4 4851.3 4853.8 4867.7 4666.3 4788.6 4789.0 4791.0 4794.2 4782.9 4784.6 4787.3 4789.6 4798.2 4147.7 4154.4 4158.5 4161.1 4163.8 5925.3 5955.5 6009.4 6029.7 6057.2 5425.4 5505.1 5521.6 5525.1 5533.3 6564.8 6673.3 6686.7 6693.1 6694.9 4545.3 4546.2 4568.1 4573.7 4600.5 6880.1 6932.8 7011.5 7015.9 7021.8 4642.6 4650.1 4691.4 4709.1 4713.3 4756.2 4764.1 4765.6 4768.7 4772.3 5546.5 5566.5 5567.4 5568.9 5579.0 6673.5 6806.1 6808.3 6820.7 6829.5 1646.1 1669.1 1687.8 1689.7 1690.0 1113.4 1120.9 1127.8 1132.0 1142.3 6432.0 6452.8 6454.5 6456.2 6470.5 6707.0 6765.3 6800.0 6834.7 6840.3 6348.0 6394.8 6435.5 6438.8 6450.8 3759.9 3766.0 3769.6 3775.4 3787.8 7073.4 7110.9 7118.0 7121.1 7132.4 6946.3 7050.6 7058.6 7072.5 7087.9 7057.5 7069.4 7087.6 7114.0 7122.9 6850.6 6874.9 6896.5 6903.0 6913.0 6550.3 6744.6 6750.7 6797.4 6816.8 7062.3 7100.8 7103.3 7103.5 7112.6 4669.6 4680.4 4683.3 4688.7 4689.7 6822.9 6857.0 6862.1 6903.3 7083.2 6844.8 6858.7 6863.0 6865.5 6876.5 6845.0 6849.6 6853.4 6855.9 6866.5 6539.2 6579.1 6592.2 6620.3 6696.2 6692.2 6775.2 6823.9 6826.2 6833.0 6584.0 6619.1 6633.1 6634.6 6658.6 12:51:06 INFO - 2015-11-18 12:51:06,266 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,266 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13804.67 Median:25233.25 stddev:16258.28 (64.4%) stddev-sans-first:16274.20 12:51:06 INFO - 2015-11-18 12:51:06,266 DEBUG : BROWSER_OUTPUT: Values: 720.5 831.9 834.3 850.1 887.1 13162.2 13457.0 13507.3 13554.8 13607.7 12936.2 13119.6 13138.5 14379.6 14765.6 790.9 807.4 808.5 808.6 811.0 816.6 818.4 820.8 823.9 824.3 854.1 864.9 865.9 866.6 868.1 852.7 857.9 858.0 858.5 861.0 620.9 622.8 624.5 626.3 627.3 13694.7 13795.4 13840.4 13861.4 13885.9 10157.0 10160.8 10304.2 10482.7 10953.8 10084.8 10163.7 10185.1 10230.3 10235.5 3185.9 3228.2 3268.5 3277.9 3299.4 46004.5 46420.5 46525.3 46633.5 46776.8 7504.2 7554.2 7615.2 7702.7 7759.5 11714.9 11795.9 11861.6 11864.2 11888.9 6721.5 6765.5 6817.0 6828.5 6982.4 4250.8 4284.6 4313.2 4336.3 4352.2 2727.1 2736.9 2740.1 2768.3 2786.3 454.9 463.3 472.2 476.4 477.2 517.4 581.4 581.5 677.7 687.0 8713.7 8942.0 9056.8 9230.3 9805.4 587.0 686.1 693.3 705.9 712.0 4152.5 4447.2 4450.8 4548.4 4589.2 57398.7 57729.4 57852.4 57942.7 58263.7 52246.5 52748.2 53027.5 53368.7 55760.0 45877.3 46650.0 46926.0 48375.8 49205.0 8840.2 8864.6 9016.4 9031.7 9144.5 36605.5 36802.4 37482.0 37973.8 38068.4 42775.4 46087.0 47055.8 47107.8 47548.3 11648.1 11858.8 11903.4 11941.2 11977.7 12414.8 12455.5 12464.4 12747.8 12876.4 11924.5 11959.8 12122.7 12286.6 12456.9 11573.0 11811.7 11835.2 11882.2 12236.4 11413.3 11462.1 11930.9 11935.2 12065.4 10224.1 10296.2 10522.9 10710.1 10799.6 9733.6 10357.0 10395.0 10564.6 10626.9 12:51:06 INFO - 2015-11-18 12:51:06,266 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:51:06 INFO - 2015-11-18 12:51:06,266 DEBUG : BROWSER_OUTPUT: 12:51:06 INFO - 2015-11-18 12:51:06,918 INFO : Browser exited with error code: 0 12:51:06 INFO - 2015-11-18 12:51:06,936 INFO : Completed test dromaeo_css (00:18:31) 12:51:06 INFO - 2015-11-18 12:51:06,937 INFO : Starting test kraken 12:51:06 INFO - 2015-11-18 12:51:06,937 DEBUG : operating with platform_type : linux_ 12:51:06 INFO - 2015-11-18 12:51:06,937 INFO : Initialising browser for kraken test... 12:51:06 INFO - 2015-11-18 12:51:06,943 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0_NugP/profile http://localhost:42284/getInfo.html 12:51:11 INFO - 2015-11-18 12:51:11,749 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:51:11 INFO - 2015-11-18 12:51:11,750 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:51:11 INFO - 2015-11-18 12:51:11,750 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:51:11 INFO - 2015-11-18 12:51:11,750 DEBUG : BROWSER_OUTPUT: __metrics 12:51:12 INFO - 2015-11-18 12:51:12,796 INFO : Browser initialized. 12:51:12 INFO - 2015-11-18 12:51:12,797 INFO : Running cycle 1/1 for kraken test... 12:51:12 INFO - 2015-11-18 12:51:12,797 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0_NugP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:51:24 INFO - 2015-11-18 12:51:24,555 DEBUG : BROWSER_OUTPUT: RSS: Main: 141295616 12:51:24 INFO - 2015-11-18 12:51:24,555 DEBUG : BROWSER_OUTPUT: 12:51:27 INFO - 2015-11-18 12:51:27,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-beat-detection) 12:51:27 INFO - 2015-11-18 12:51:27,662 DEBUG : BROWSER_OUTPUT: RSS: Main: 141410304 12:51:27 INFO - 2015-11-18 12:51:27,662 DEBUG : BROWSER_OUTPUT: 12:51:30 INFO - 2015-11-18 12:51:30,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-dft) 12:51:30 INFO - 2015-11-18 12:51:30,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 141787136 12:51:30 INFO - 2015-11-18 12:51:30,880 DEBUG : BROWSER_OUTPUT: 12:51:33 INFO - 2015-11-18 12:51:33,730 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-fft) 12:51:34 INFO - 2015-11-18 12:51:34,311 DEBUG : BROWSER_OUTPUT: RSS: Main: 135852032 12:51:34 INFO - 2015-11-18 12:51:34,311 DEBUG : BROWSER_OUTPUT: 12:51:36 INFO - 2015-11-18 12:51:36,284 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-oscillator) 12:51:36 INFO - 2015-11-18 12:51:36,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 136765440 12:51:36 INFO - 2015-11-18 12:51:36,858 DEBUG : BROWSER_OUTPUT: 12:51:39 INFO - 2015-11-18 12:51:39,044 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:51:39 INFO - 2015-11-18 12:51:39,627 DEBUG : BROWSER_OUTPUT: RSS: Main: 137117696 12:51:39 INFO - 2015-11-18 12:51:39,627 DEBUG : BROWSER_OUTPUT: 12:51:42 INFO - 2015-11-18 12:51:42,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42284/tests/kraken/driver.html?testName=imaging-darkroom) 12:51:43 INFO - 2015-11-18 12:51:43,207 DEBUG : BROWSER_OUTPUT: RSS: Main: 137216000 12:51:43 INFO - 2015-11-18 12:51:43,208 DEBUG : BROWSER_OUTPUT: 12:51:46 INFO - 2015-11-18 12:51:46,610 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42284/tests/kraken/driver.html?testName=imaging-desaturate) 12:51:47 INFO - 2015-11-18 12:51:47,199 DEBUG : BROWSER_OUTPUT: RSS: Main: 138604544 12:51:47 INFO - 2015-11-18 12:51:47,199 DEBUG : BROWSER_OUTPUT: 12:51:50 INFO - 2015-11-18 12:51:50,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42284/tests/kraken/driver.html?testName=json-parse-financial) 12:51:50 INFO - 2015-11-18 12:51:50,761 DEBUG : BROWSER_OUTPUT: RSS: Main: 139489280 12:51:50 INFO - 2015-11-18 12:51:50,761 DEBUG : BROWSER_OUTPUT: 12:51:52 INFO - 2015-11-18 12:51:52,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:51:52 INFO - 2015-11-18 12:51:52,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 137461760 12:51:52 INFO - 2015-11-18 12:51:52,955 DEBUG : BROWSER_OUTPUT: 12:51:54 INFO - 2015-11-18 12:51:54,778 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:51:55 INFO - 2015-11-18 12:51:55,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 139280384 12:51:55 INFO - 2015-11-18 12:51:55,385 DEBUG : BROWSER_OUTPUT: 12:51:57 INFO - 2015-11-18 12:51:57,478 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:51:58 INFO - 2015-11-18 12:51:58,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 139481088 12:51:58 INFO - 2015-11-18 12:51:58,060 DEBUG : BROWSER_OUTPUT: 12:52:00 INFO - 2015-11-18 12:52:00,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:52:01 INFO - 2015-11-18 12:52:01,121 DEBUG : BROWSER_OUTPUT: RSS: Main: 140009472 12:52:01 INFO - 2015-11-18 12:52:01,121 DEBUG : BROWSER_OUTPUT: 12:52:03 INFO - 2015-11-18 12:52:03,676 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:52:04 INFO - 2015-11-18 12:52:04,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 138723328 12:52:04 INFO - 2015-11-18 12:52:04,262 DEBUG : BROWSER_OUTPUT: 12:52:05 INFO - 2015-11-18 12:52:05,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42284/tests/kraken/driver.html?testName=ai-astar) 12:52:06 INFO - 2015-11-18 12:52:06,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 138883072 12:52:06 INFO - 2015-11-18 12:52:06,194 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,195 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:52:06 INFO - 2015-11-18 12:52:06,195 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:52:06 INFO - 2015-11-18 12:52:06,195 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:52:06 INFO - 2015-11-18 12:52:06,195 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:52:06 INFO - 2015-11-18 12:52:06,196 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;106;110;113;112;123;110;109;111;111 12:52:06 INFO - 2015-11-18 12:52:06,196 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;153;125;154;158;129;159;158;128;166;126 12:52:06 INFO - 2015-11-18 12:52:06,196 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;163;161;161;163;160;164;165;170;162 12:52:06 INFO - 2015-11-18 12:52:06,196 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;74;72;73;73;139;72;73;71;73 12:52:06 INFO - 2015-11-18 12:52:06,197 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;92;138;91;92;91;143;91;92;133 12:52:06 INFO - 2015-11-18 12:52:06,197 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;110;110;110;109;110;110;110 12:52:06 INFO - 2015-11-18 12:52:06,197 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;147;146;147;147;147;147;147;147 12:52:06 INFO - 2015-11-18 12:52:06,197 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;96;96;98;96;96;96;95 12:52:06 INFO - 2015-11-18 12:52:06,197 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;63;62;61;63;63;71;62;69;59 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;71;69;70;70;70;69;69;70;70 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;92;86;85;86;84;86;86;85;84 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;132;127;118;119;128;122;117;124;133;117 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;153;143;151;151;155;148;156;148;143 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;66;69;68;67;70;65;69;66;70 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:52:06 INFO - 2015-11-18 12:52:06,198 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:52:06 INFO - 2015-11-18 12:52:06,199 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,725 12:52:06 INFO - 2015-11-18 12:52:06,199 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:52:06 INFO - 2015-11-18 12:52:06,199 DEBUG : BROWSER_OUTPUT: __startTimestamp1447879926193__endTimestamp 12:52:06 INFO - 2015-11-18 12:52:06,199 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:4.43 (4.0%) stddev-sans-first:4.69 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: Values: 111.0 106.0 110.0 113.0 112.0 123.0 110.0 109.0 111.0 111.0 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.60 Median:156.00 stddev:16.41 (10.5%) stddev-sans-first:17.18 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: Values: 153.0 125.0 154.0 158.0 129.0 159.0 158.0 128.0 166.0 126.0 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:2.83 (1.7%) stddev-sans-first:2.99 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: Values: 164.0 163.0 161.0 161.0 163.0 160.0 164.0 165.0 170.0 162.0 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,200 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.10 Median:73.00 stddev:21.07 (28.9%) stddev-sans-first:22.14 12:52:06 INFO - 2015-11-18 12:52:06,202 DEBUG : BROWSER_OUTPUT: Values: 71.0 74.0 72.0 73.0 73.0 139.0 72.0 73.0 71.0 73.0 12:52:06 INFO - 2015-11-18 12:52:06,202 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,202 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:91.00 stddev:22.70 (24.9%) stddev-sans-first:23.39 12:52:06 INFO - 2015-11-18 12:52:06,202 DEBUG : BROWSER_OUTPUT: Values: 90.0 92.0 138.0 91.0 92.0 91.0 143.0 91.0 92.0 133.0 12:52:06 INFO - 2015-11-18 12:52:06,202 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,203 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:52:06 INFO - 2015-11-18 12:52:06,203 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 110.0 110.0 110.0 109.0 110.0 110.0 110.0 12:52:06 INFO - 2015-11-18 12:52:06,203 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,203 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 12:52:06 INFO - 2015-11-18 12:52:06,203 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 147.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 12:52:06 INFO - 2015-11-18 12:52:06,203 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 96.0 96.0 98.0 96.0 96.0 96.0 95.0 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.00 stddev:3.80 (6.0%) stddev-sans-first:3.84 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: Values: 60.0 63.0 62.0 61.0 63.0 63.0 71.0 62.0 69.0 59.0 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: Values: 69.0 71.0 69.0 70.0 70.0 70.0 69.0 69.0 70.0 70.0 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,204 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.00 stddev:2.45 (2.9%) stddev-sans-first:2.40 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: Values: 83.0 92.0 86.0 85.0 86.0 84.0 86.0 86.0 85.0 84.0 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:125.50 stddev:6.07 (4.8%) stddev-sans-first:5.65 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: Values: 132.0 127.0 118.0 119.0 128.0 122.0 117.0 124.0 133.0 117.0 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.90 Median:151.00 stddev:5.24 (3.5%) stddev-sans-first:4.71 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: Values: 141.0 153.0 143.0 151.0 151.0 155.0 148.0 156.0 148.0 143.0 12:52:06 INFO - 2015-11-18 12:52:06,205 DEBUG : BROWSER_OUTPUT: 12:52:06 INFO - 2015-11-18 12:52:06,206 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.50 stddev:1.75 (2.6%) stddev-sans-first:1.86 12:52:06 INFO - 2015-11-18 12:52:06,206 DEBUG : BROWSER_OUTPUT: Values: 68.0 66.0 69.0 68.0 67.0 70.0 65.0 69.0 66.0 70.0 12:52:06 INFO - 2015-11-18 12:52:06,206 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:52:06 INFO - 2015-11-18 12:52:06,206 DEBUG : BROWSER_OUTPUT: 12:52:07 INFO - 2015-11-18 12:52:07,090 INFO : Browser exited with error code: 0 12:52:07 INFO - 2015-11-18 12:52:07,138 INFO : Completed test kraken (00:01:00) 12:52:07 INFO - 2015-11-18 12:52:07,138 INFO : Starting test v8_7 12:52:07 INFO - 2015-11-18 12:52:07,138 DEBUG : operating with platform_type : linux_ 12:52:07 INFO - 2015-11-18 12:52:07,139 INFO : Initialising browser for v8_7 test... 12:52:07 INFO - 2015-11-18 12:52:07,144 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpp9zbx4/profile http://localhost:42284/getInfo.html 12:52:11 INFO - 2015-11-18 12:52:11,859 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:52:11 INFO - 2015-11-18 12:52:11,859 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:52:11 INFO - 2015-11-18 12:52:11,859 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:52:11 INFO - 2015-11-18 12:52:11,859 DEBUG : BROWSER_OUTPUT: __metrics 12:52:12 INFO - 2015-11-18 12:52:12,942 INFO : Browser initialized. 12:52:12 INFO - 2015-11-18 12:52:12,942 INFO : Running cycle 1/1 for v8_7 test... 12:52:12 INFO - 2015-11-18 12:52:12,942 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpp9zbx4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:52:24 INFO - 2015-11-18 12:52:24,726 DEBUG : BROWSER_OUTPUT: RSS: Main: 141471744 12:52:24 INFO - 2015-11-18 12:52:24,726 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,136 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42284/tests/v8_7/run.html (next: http://localhost:42284/tests/v8_7/run.html) 12:52:46 INFO - 2015-11-18 12:52:46,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 138543104 12:52:46 INFO - 2015-11-18 12:52:46,382 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,382 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:52:46 INFO - 2015-11-18 12:52:46,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:52:46 INFO - 2015-11-18 12:52:46,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:52:46 INFO - 2015-11-18 12:52:46,383 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:52:46 INFO - 2015-11-18 12:52:46,383 DEBUG : BROWSER_OUTPUT: |0;Richards;163.6929120969062 12:52:46 INFO - 2015-11-18 12:52:46,383 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.25019425019426 12:52:46 INFO - 2015-11-18 12:52:46,384 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.7380410022779 12:52:46 INFO - 2015-11-18 12:52:46,384 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5055.555555555556 12:52:46 INFO - 2015-11-18 12:52:46,384 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1059.322033898305 12:52:46 INFO - 2015-11-18 12:52:46,384 DEBUG : BROWSER_OUTPUT: |5;Earley;701.7543859649123 12:52:46 INFO - 2015-11-18 12:52:46,385 DEBUG : BROWSER_OUTPUT: |6;Boyer;12120.481927710844 12:52:46 INFO - 2015-11-18 12:52:46,385 DEBUG : BROWSER_OUTPUT: |7;RegExp;25175 12:52:46 INFO - 2015-11-18 12:52:46,385 DEBUG : BROWSER_OUTPUT: |8;Splay;630.119722747322 12:52:46 INFO - 2015-11-18 12:52:46,385 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 12:52:46 INFO - 2015-11-18 12:52:46,386 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:52:46 INFO - 2015-11-18 12:52:46,386 DEBUG : BROWSER_OUTPUT: __startTimestamp1447879966381__endTimestamp 12:52:46 INFO - 2015-11-18 12:52:46,386 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,386 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:52:46 INFO - 2015-11-18 12:52:46,387 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:52:46 INFO - 2015-11-18 12:52:46,387 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,387 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.69 Median:163.69 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,387 DEBUG : BROWSER_OUTPUT: Values: 163.7 12:52:46 INFO - 2015-11-18 12:52:46,387 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,387 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.25 Median:194.25 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,388 DEBUG : BROWSER_OUTPUT: Values: 194.3 12:52:46 INFO - 2015-11-18 12:52:46,388 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,388 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.74 Median:284.74 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,389 DEBUG : BROWSER_OUTPUT: Values: 284.7 12:52:46 INFO - 2015-11-18 12:52:46,389 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,389 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5055.56 Median:5055.56 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,389 DEBUG : BROWSER_OUTPUT: Values: 5055.6 12:52:46 INFO - 2015-11-18 12:52:46,389 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1059.32 Median:1059.32 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: Values: 1059.3 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:701.75 Median:701.75 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: Values: 701.8 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12120.48 Median:12120.48 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,390 DEBUG : BROWSER_OUTPUT: Values: 12120.5 12:52:46 INFO - 2015-11-18 12:52:46,391 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,391 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25175.00 Median:25175.00 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,391 DEBUG : BROWSER_OUTPUT: Values: 25175.0 12:52:46 INFO - 2015-11-18 12:52:46,391 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,392 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:630.12 Median:630.12 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,392 DEBUG : BROWSER_OUTPUT: Values: 630.1 12:52:46 INFO - 2015-11-18 12:52:46,392 DEBUG : BROWSER_OUTPUT: 12:52:46 INFO - 2015-11-18 12:52:46,392 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 12:52:46 INFO - 2015-11-18 12:52:46,392 DEBUG : BROWSER_OUTPUT: Values: 4850.2 12:52:46 INFO - 2015-11-18 12:52:46,393 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:52:46 INFO - 2015-11-18 12:52:46,393 DEBUG : BROWSER_OUTPUT: 12:52:47 INFO - 2015-11-18 12:52:47,250 INFO : Browser exited with error code: 0 12:52:47 INFO - 2015-11-18 12:52:47,264 INFO : Completed test v8_7 (00:00:40) 12:52:47 INFO - 2015-11-18 12:52:47,406 INFO : Completed test suite (00:20:12) 12:52:47 INFO - 2015-11-18 12:52:47,406 DEBUG : Working with test: dromaeo_css 12:52:47 INFO - 2015-11-18 12:52:47,406 DEBUG : Generating results file: dromaeo_css 12:52:47 INFO - 2015-11-18 12:52:47,408 DEBUG : Working with test: kraken 12:52:47 INFO - 2015-11-18 12:52:47,408 DEBUG : Generating results file: kraken 12:52:47 INFO - 2015-11-18 12:52:47,408 INFO : javascript benchmark 12:52:47 INFO - 2015-11-18 12:52:47,408 DEBUG : Working with test: v8_7 12:52:47 INFO - 2015-11-18 12:52:47,408 DEBUG : Generating results file: v8_7 12:52:47 INFO - 2015-11-18 12:52:47,409 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:52:47 INFO - 2015-11-18 12:52:47,461 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 12:52:47 INFO - 2015-11-18 12:52:47,461 DEBUG : process_Request line: dromaeo_css 6726.24 graph.html#tests=[[72,63,43]] 12:52:47 INFO - 2015-11-18 12:52:47,461 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:52:47 INFO - 2015-11-18 12:52:47,490 DEBUG : process_Request line: kraken 1516.70 graph.html#tests=[[232,63,43]] 12:52:47 INFO - 2015-11-18 12:52:47,490 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:52:47 INFO - 2015-11-18 12:52:47,519 DEBUG : process_Request line: v8_7 21344.88 graph.html#tests=[[230,63,43]] 12:52:47 INFO - 2015-11-18 12:52:47,520 INFO : javascript benchmark 12:52:47 INFO - 2015-11-18 12:52:47,524 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3211.61460624725, 3254.4955531892565, 3273.9508907366394, 3285.8357082145885, 3306.768526203165, 5175.301334319865, 5327.0, 5343.091674415349, 5382.757775900084, 5447.428020057891, 5194.0910340690325, 5227.294315267438, 5286.947130528693, 5287.259783630295, 5382.569394448445, 4933.40799104108, 5030.270610761438, 5077.416097148838, 5089.643724939256, 5117.437081920985, 4986.875328116808, 5053.949460505403, 5067.619928505358, 5073.923891141636, 5122.487751224885, 4780.139574876521, 4836.105320515716, 4879.780409881564, 5004.624653151028, 5023.2214006828635, 4985.775640096168, 4990.326305948687, 4997.05056039355, 5014.623903207274, 5082.390113186441, 4872.317875497433, 4974.9751251243515, 4992.276119962621, 4993.051320249141, 5054.216596427523, 5220.3735551734035, 5279.208118782213, 5279.340082489689, 5286.656367336111, 5339.919901201485, 5821.359650438449, 5917.408259174092, 5933.643981361089, 5936.792212272593, 5943.52451803859, 5923.17075609415, 5930.932432162209, 5972.4027597240365, 5973.223480947492, 5978.521718262572, 5375.596830237709, 5445.319335083154, 5451.618386712932, 5526.392096869341, 5593.216949627055, 6007.639541627429, 6052.6671033092725, 6070.180525629072, 6075.908861367083, 6080.14877917092, 5880.970595146997, 5883.823485295448, 5885.588008839426, 5894.410558944071, 5896.587238893322, 5685.602007859493, 5839.0949402842625, 5854.7072646367515, 5864.824055278348, 5869.7945571904775, 5773.913391299134, 5790.565707571949, 5844.970775146097, 5857.20927674767, 5877.030290002187, 5834.329052159056, 5839.941600584025, 5894.8821023579285, 5944.910826337608, 5975.4024597540765, 4358.0412309291905, 4377.124575084932, 4470.932936005963, 4520.751358675238, 4542.636589072932, 3982.820773065219, 3992.3612222044912, 4005.1388951375593, 4012.6990475714438, 4028.576999415019, 5573.693446860461, 5588.6646801192055, 5600.439956004367, 5632.549396048306, 5645.435456454322, 5721.656700597978, 5874.794382196603, 5879.823605291848, 5894.8821023579285, 5907.527397808165, 5589.720513974285, 5688.914666280009, 5702.8289151325525, 5705.486506214461, 5712.171735098387, 5098.08234517783, 5108.0, 5109.4379618241965, 5127.154019586801, 5130.153524668388, 5778.94221057784, 5819.952408566414, 5865.472107510259, 5933.970330148321, 6054.0, 5890.882182356243, 5943.554233432424, 5945.048792193228, 5962.046072628359, 5967.075103358986, 5985.341612422717, 6066.1507388966465, 6067.696615169312, 6079.787207447718, 6087.086936959315, 5629.0712032514175, 5697.487226149667, 5768.1636162757, 5848.2689663792025, 5901.881962360814, 5861.325947515919, 5914.763409463573, 5937.376575459514, 5948.196993405835, 5959.91060134107, 5797.797077102281, 5833.4749872511675, 5842.298924129132, 5845.298564172326, 5845.561582881386, 5691.060974939089, 5731.570132240182, 5821.650700957956, 5865.530757539302, 5906.852328691645, 5673.0, 5679.091345384719, 5688.0, 5688.459596338341, 5689.630174038674, 5638.464345887298, 5743.282089738783, 5803.941960580509, 5824.854378640568, 5831.591788574759, 5621.353544342453, 5675.77296908119, 5681.460261275172, 5695.829125126253, 5704.087346024616], "unit": "ms", "name": "dojo.html", "value": 5415.694847797422}, {"lowerIsBetter": false, "replicates": [550.4346276830172, 562.361719448426, 596.5317225977608, 598.6617561077992, 605.7970579855748, 31505.21236969074, 32405.51391729126, 39755.80122099389, 40084.19410641249, 40183.0, 43163.0, 43718.0, 43805.78097109518, 43940.560594394046, 44115.55884441155, 8754.255888249507, 8879.689210877621, 8891.510966896818, 8895.28837692983, 8913.1978121969, 8648.048714641385, 8728.127187281252, 8765.430247033953, 8769.429987050853, 8771.956140219321, 8718.389712720113, 8768.210861022506, 8808.559572021406, 8815.294776417903, 8826.426282291663, 8698.782530436727, 8780.780480488007, 8781.692640757576, 8791.736247912573, 8799.912000879973, 8576.957115214384, 8697.565121743888, 8763.342749293828, 8779.736607901774, 8781.7804554886, 39835.40246896299, 40193.39709904354, 40413.79793101045, 40524.0, 40709.185816283505, 3143.5913331267016, 3154.3218208085373, 3199.9200019999453, 3299.835008249578, 3518.4194607889667, 29219.41561168786, 29541.113766587037, 30292.8485357574, 30335.0, 30770.23074423135, 3305.8347082645773, 3344.832758362072, 3406.0, 3409.9488507672404, 3452.413089774719, 227984.0, 228523.0, 230029.0, 231536.0, 231959.0, 40873.795631021654, 41038.179236415104, 41641.58358416438, 55372.72313638487, 55939.44060559342, 40932.181356372705, 40952.0, 41158.176836463106, 41247.587524124974, 41718.0, 14608.780868286984, 14790.260486975609, 14792.482263120739, 14799.112053276838, 14800.555983320517, 10729.624463143753, 10780.353178809297, 10800.351978881292, 10832.350058996328, 10886.40124793144, 13931.860681393258, 13935.233562153977, 13951.232682202373, 14006.929965350108, 14161.929190353982, 5950.970245148833, 5963.314218864886, 5983.649156472389, 6082.0, 6104.847378815565, 929.159111004542, 952.8613307097951, 1011.3830563356347, 1035.5081336365167, 1121.489722176424, 29000.0, 29115.272118197216, 29578.40843183124, 29817.552736708967, 29842.0, 1003.1673710820002, 1016.3952448293254, 1089.803835309636, 1094.1684319916922, 1106.9096939514732, 15039.924800375928, 15090.320935557926, 15102.848971510364, 15162.924185379223, 15175.0, 311487.0, 313299.0, 315291.0, 316751.0, 317359.0, 51685.0, 52156.47843521592, 52259.47740522546, 52304.95390092177, 52370.0, 1263.2799304396406, 1271.9173253738477, 1292.0, 1302.5050480817229, 1306.4186437035428, 28322.433551328446, 28717.28206794847, 29065.564016540193, 29120.0, 29488.262793430335, 45864.31203531883, 46114.769426152656, 46365.768171158925, 46404.07191856077, 46470.53529464662, 281074.0, 288090.0, 289054.0, 290662.0, 292597.0, 32690.182745431553, 41199.1760164801, 42195.36706949337, 42319.518816841264, 42895.7855210722, 42050.78974605107, 43336.133277334906, 43360.87744112732, 44136.77931610322, 44469.77765111283, 36834.0, 36848.07879802919, 37775.81112094422, 38402.807985959895, 38542.0, 36442.27115457729, 36547.634523655484, 37275.69535066368, 37518.24963500769, 37684.62315376811, 36292.274154516235, 36874.0, 37135.0, 37186.442203367515, 37308.6269137316, 32518.0, 33606.49590256197, 33790.66209337875, 34487.827560863036, 35103.47344789779, 25842.449453032877, 26029.739702602732, 27197.728022720312, 28236.0, 28611.284717881386], "unit": "ms", "name": "ext.html", "value": 17902.670041249985}, {"lowerIsBetter": false, "replicates": [349.7918738350682, 358.70406914295705, 360.1213040181954, 362.2519497238203, 368.10550362618824, 12613.117081804277, 13022.93488532556, 16979.915100424496, 18028.18873150708, 19003.714944275813, 12010.0992425568, 12048.518059277618, 12212.32832194229, 12216.938915305433, 12239.877601223985, 4473.105378924212, 4578.542145785428, 4583.335416364625, 4594.379758732562, 4594.632429405639, 4767.523247675239, 4893.241547560134, 4898.804047838083, 4903.411590609114, 4905.583025442842, 4688.742119183444, 4806.7356295403915, 4812.711237325754, 4843.0, 4850.830220942268, 4758.952410475886, 4777.235642297216, 4778.331033655309, 4850.393700787402, 4871.26930960357, 4125.443065186191, 4142.834286628531, 4149.605787450188, 4203.390508376268, 4334.024844410014, 13556.186628802303, 13571.45714171432, 13826.723465530733, 13828.308584570732, 14310.427582896671, 2060.3406909788873, 2062.3400511836217, 2077.0, 2144.8927553622257, 2196.4399078235106, 9738.75653108678, 9839.655612053615, 9881.555330010095, 9964.551595178234, 10251.026152515498, 1681.9747703784453, 1691.7171576363748, 1693.7798086248831, 1728.2222999650207, 1784.0366202250768, 59765.0, 60959.085613715826, 62757.37242627561, 63419.0, 64413.355866441205, 7053.68258428372, 7165.211826699059, 7217.8556428871125, 7244.565326080452, 7253.202147763742, 8443.66225350979, 8567.871481927776, 8605.13948605147, 8607.139286071468, 8611.354148438892, 5653.208550802891, 5762.0, 5781.4218578141845, 5809.970950145223, 5822.94177058224, 5882.294124704978, 6039.124326972565, 6052.6065805723365, 6065.787697430657, 6109.175261339751, 3280.2455435250026, 3315.2540678347177, 3331.950020749691, 3351.0952042948757, 3352.430086885235, 1156.2888823373733, 1166.160399427397, 1174.1663418972626, 1174.442139983501, 1221.8533775946944, 408.8344220590665, 419.77961570175904, 420.43871431639104, 454.3162540376712, 470.0809916613082, 10388.324758890649, 10437.68686939393, 10475.214358923111, 10731.141508679288, 10760.085392741548, 435.4339358833504, 435.76452797150415, 437.90803931174173, 447.0611715397704, 448.614191795056, 5621.747021384048, 5839.824805255849, 5861.648301101947, 5974.4622983930785, 5989.700514974234, 501671.0, 503616.0, 513693.0, 515965.0, 530266.0, 76144.61927690326, 76334.618326908, 76744.23255767483, 77617.6119119412, 77645.61177194078, 492.22720329082995, 493.45226798253907, 494.1574615281009, 494.675987228366, 495.60846930924885, 10164.237682174014, 10305.072543471124, 10306.536205870603, 10318.587256509956, 10348.948255258676, 34537.48193777045, 34839.47740783839, 35642.46536302009, 35982.0, 36500.81749591341, 47493.0, 48420.273695895296, 48463.75768121278, 48897.75551122222, 49574.75212624058, 6888.827779305558, 6899.93100069013, 6912.758053468105, 6928.792136235921, 6945.687444064928, 12519.0, 12651.11442199074, 12704.0, 12825.038122141063, 12898.548550801052, 10662.306950048222, 11201.943990279997, 11239.494222760159, 11267.887321126684, 11328.546858125583, 10140.340877842917, 10594.735131621463, 10683.358998460117, 10756.193285503776, 10823.891761082288, 10916.12670986335, 11039.0, 11128.0, 11229.49467273991, 11297.774044519228, 6603.834904127243, 6983.022376867344, 6996.685149168198, 7041.014258003986, 7096.290370963069, 10814.945925270324, 10989.725256868642, 11122.944385278022, 11182.440877955907, 11409.942950285196], "unit": "ms", "name": "jquery.html", "value": 6881.290736425363}, {"lowerIsBetter": false, "replicates": [1475.4172102019695, 1487.583476626545, 1616.862566681832, 1705.9317627294906, 1763.06557524512, 6625.271220165778, 6657.90013149802, 7730.11103723072, 7952.801179970496, 7955.761327160188, 7252.492325537214, 7377.926220737791, 7422.331990120888, 7449.0, 7456.70173193073, 2211.6903633491324, 2245.5845668551287, 2250.4036430345973, 2263.1286954522543, 2289.988550057256, 2257.616205245104, 2327.7904988551027, 2333.1717240379676, 2383.380321116505, 2397.7482364351754, 2288.6338185890263, 2288.9771102288933, 2321.2223904991765, 2332.8133749300014, 2352.5882970480184, 2310.780475854791, 2316.2356422380576, 2343.742188359279, 2344.367020904346, 2355.0, 2299.8160147188187, 2323.5585238804574, 2331.6269396896414, 2335.8832058397184, 2339.520398318358, 7375.557466551971, 7432.739854105081, 7463.141738700065, 7519.812004699872, 7546.169921308651, 4671.766411679437, 4761.785719642625, 4780.163471392492, 4788.904221915577, 4803.543663351976, 3047.4514587374265, 3080.445519806434, 3087.6294844618787, 3109.937801243985, 3159.162821852211, 1052.5684469367523, 1067.3809190669392, 1071.196602548089, 1091.3942761767216, 1116.7096554895704, 10927.890721092766, 10940.617078402218, 10943.56225750969, 10965.945170274177, 10999.780004399867, 4341.804618792162, 4349.652027837766, 4411.228035093845, 4428.31361139024, 4445.355423463644, 9244.90755092454, 9276.11876871696, 9315.115064068903, 9318.207952324128, 9322.30082743797, 4685.718856868599, 4696.976515117402, 4697.271922851895, 4698.496481126075, 4719.5752382285755, 3791.829367678461, 3869.5163104611925, 4245.830166793294, 4260.510041345223, 4288.378185163197, 3639.1811842335264, 3692.1508053147927, 3705.184859330997, 3720.3489389357296, 3735.084904198489, 1211.3277131192096, 1219.4146809531474, 1223.6757259326287, 1235.7466719322517, 1236.4436003798328, 1303.5567906911506, 1311.8294621699117, 1316.6839958409912, 1320.0495643136926, 1326.694860182144, 7150.0, 7243.130824301117, 7253.455990800711, 7266.128064632172, 7317.195108538057, 491.8081948040267, 501.6237821633775, 514.6371807875439, 515.8787684894047, 521.3274875410677, 5723.341815691166, 5751.424857514216, 5757.596968212269, 5763.769449222068, 5836.708164591839, 8964.14840590143, 9267.814643707088, 9295.11696388842, 9385.108414700593, 9494.525273736286, 9259.981402045769, 9309.581068851918, 9391.154796068387, 9447.480388578695, 9474.336796424184, 7044.682989265495, 7129.251428600026, 7140.750073747394, 7306.525075870051, 7307.378872795872, 6880.208775990825, 6961.965190174219, 7026.262242464672, 7056.364927156581, 7067.929320706727, 8028.263324638491, 8162.959185204036, 8356.331493480386, 8362.372822038249, 8388.328933685412, 8499.022612399405, 8511.489310641382, 8626.39615226928, 8626.611802468778, 8627.568621569022, 6337.366263373626, 6783.94848798437, 6817.284185160487, 6823.55646882951, 7073.328033836778, 7291.453141014551, 7491.737789177353, 7506.924930750841, 7542.396608271435, 7559.055118110236, 6877.449804015567, 7012.474064445289, 7100.609466479186, 7124.145102587722, 7158.785236442915, 7084.929150708633, 7112.466565007541, 7115.181754098138, 7208.315210055036, 7211.67547460355, 6807.727690892507, 7058.54119482232, 7060.082189315356, 7072.292770722886, 7101.964490177516, 6446.129772480655, 6557.803265902031, 6572.277049524644, 6576.210854697467, 6597.967010164918, 6606.900896486461, 6670.232923213893, 6693.397594216543, 6696.0, 6697.531172817856], "unit": "ms", "name": "mootools.html", "value": 4428.444532641385}, {"lowerIsBetter": false, "replicates": [344.204886711696, 348.7576134586463, 349.7953697087204, 350.5652990292037, 353.2793102071772, 6274.874502509947, 6278.968605156968, 6332.62004279743, 6365.331640177784, 6470.450011749007, 6303.621782693031, 6316.621002739827, 6339.334369891164, 6343.6193828370215, 6376.904346434807, 4825.806967721287, 4832.975835120819, 4835.202191638376, 4837.201861692816, 4842.1042107210105, 4839.128956787777, 4847.351900353879, 4851.345068415754, 4853.757312134397, 4867.707937523742, 4666.253399456088, 4788.61691064714, 4789.0, 4791.041791641685, 4794.208955522335, 4782.856514304559, 4784.5933095686905, 4787.305840653085, 4789.568938795508, 4798.23228283473, 4147.668186545069, 4154.439150714645, 4158.500979882433, 4161.146964872194, 4163.75017498948, 5925.318588362307, 5955.464008239279, 6009.369016253274, 6029.668368239745, 6057.212562366908, 5425.430329815392, 5505.119180931072, 5521.585881058937, 5525.060739674265, 5533.3083364579425, 6564.785514679799, 6673.299303573152, 6686.6656667166935, 6693.062971184038, 6694.933050669529, 4545.272756358967, 4546.249868771649, 4568.132054909556, 4573.702709323917, 4600.5169457207185, 6880.071190389283, 6932.792016239521, 7011.474139439511, 7015.8596828063155, 7021.754238601625, 4642.605378542794, 4650.093231819763, 4691.366665500182, 4709.128811169876, 4713.269443236304, 4756.239001759702, 4764.094821983812, 4765.571098601074, 4768.737719425396, 4772.260299653559, 5546.528545073714, 5566.526845218121, 5567.387587365387, 5568.860778480571, 5578.972105139448, 6673.4661227101715, 6806.149231346082, 6808.285130061371, 6820.658967051628, 6829.487788415889, 1646.1193261604983, 1669.0736641164272, 1687.8480936715755, 1689.6536210076904, 1690.0366790929297, 1113.4265853085667, 1120.9327440353604, 1127.761393633653, 1132.0321125437797, 1142.3146112332533, 6432.0, 6452.774152904626, 6454.451371633464, 6456.192975878015, 6470.54706170573, 6707.027481015225, 6765.255821859591, 6800.0, 6834.6582670866255, 6840.31596840322, 6348.037996717616, 6394.776182833579, 6435.485161187094, 6438.8390290243115, 6450.774222902176, 3759.8684046058256, 3765.981170094132, 3769.6418840210135, 3775.3959366501226, 3787.791671458096, 7073.363397294268, 7110.928890711027, 7118.0, 7121.074260346122, 7132.429405647639, 6946.305369463013, 7050.647467626598, 7058.647067646597, 7072.469564782662, 7087.89368159478, 7057.505974581833, 7069.434445244368, 7087.625645408846, 7113.964430177816, 7122.928770712227, 6850.554713943578, 6874.896876546956, 6896.4827637926355, 6903.0, 6912.963055541709, 6550.312217217123, 6744.62904540255, 6750.6624668767345, 6797.422219111233, 6816.795496135124, 7062.329078737512, 7100.751473698396, 7103.2541583133025, 7103.467239957127, 7112.573245605074, 4669.626429885669, 4680.3915490985955, 4683.320918466871, 4688.671792974459, 4689.733771881641, 6822.931770682429, 6857.0, 6862.073620061427, 6903.309669033258, 7083.185433675193, 6844.794656160323, 6858.657067146523, 6863.0, 6865.519413640997, 6876.518643694893, 6845.041694162921, 6849.589024658537, 6853.383195512428, 6855.868781651171, 6866.450683945172, 6539.21529416454, 6579.078928950046, 6592.17597800275, 6620.271770105188, 6696.19645642526, 6692.196936367667, 6775.186977562724, 6823.918704794886, 6826.214985276558, 6833.0, 6584.045313429621, 6619.073329733745, 6633.1045308882685, 6634.635095069818, 6658.600483970978], "unit": "ms", "name": "prototype.html", "value": 5041.181370724093}, {"lowerIsBetter": false, "replicates": [720.456055677963, 831.8918540589723, 834.3075247544539, 850.1158794853353, 887.1217494680265, 13162.21026738395, 13457.0, 13507.324633768298, 13554.796678049825, 13607.659808504792, 12936.223826570413, 13119.606411807637, 13138.540151094714, 14379.568612941603, 14765.557033288991, 790.881367794831, 807.4428644235459, 808.5391326943644, 808.5876203136384, 811.0348685064783, 816.6243527977138, 818.382121498268, 820.8153165537766, 823.8558252305852, 824.2746383182821, 854.086127843208, 864.9048604653483, 865.8917635295588, 866.6273502393968, 868.1231955724701, 852.7186028610544, 857.8927634045745, 858.0304256190497, 858.5492616376453, 860.9741707748809, 620.9161763162006, 622.8318354044428, 624.4972796898495, 626.3048016701458, 627.3036929008797, 13694.65763355924, 13795.379207935566, 13840.377183026789, 13861.37623806921, 13885.861141388557, 10157.0, 10160.79678406435, 10304.227182961233, 10482.68551943443, 10953.83569246462, 10084.848727269024, 10163.695089147337, 10185.13426358753, 10230.283880128394, 10235.488225588691, 3185.904422867318, 3228.1929517620597, 3268.542404063433, 3277.885274015422, 3299.4390953537954, 46004.53995460036, 46420.53579464196, 46525.30212046822, 46633.5336646636, 46776.7661161692, 7504.249575042452, 7554.206808285161, 7615.238476152451, 7702.691892324245, 7759.456838021284, 11714.94142529299, 11795.941020294844, 11861.5848445304, 11864.16950813452, 11888.881111188777, 6721.529492935545, 6765.458763298924, 6817.0, 6828.453723702092, 6982.371586557234, 4250.787460626956, 4284.635805956466, 4313.245182093121, 4336.284513055373, 4352.173087113439, 2727.0727952496245, 2736.87684054218, 2740.082072505733, 2768.321761168527, 2786.2616406652255, 454.85603706677733, 463.25878594248934, 472.230264668588, 476.4425622022173, 477.191160982132, 517.428241792821, 581.4011568084879, 581.5492992930428, 677.6510097299895, 687.0153111950666, 8713.651453941771, 8942.0, 9056.818863622691, 9230.307726920508, 9805.411675299505, 586.9501092407107, 686.0566720758957, 693.3032302535919, 705.947053970944, 711.989320160198, 4152.501699796044, 4447.177272204653, 4450.777461126931, 4548.408706868086, 4589.187271027925, 57398.71300643554, 57729.42270577324, 57852.4214757847, 57942.71028644914, 58263.70868145632, 52246.47753522492, 52748.20877686761, 53027.469725303024, 53368.73315633475, 55760.0, 45877.31184032242, 46650.0, 46926.0, 48375.758121209874, 49205.0, 8840.24857887061, 8864.601092950703, 9016.368854180408, 9031.683891063782, 9144.451332920045, 36605.450918235714, 36802.447963280036, 37482.0, 37973.77222936082, 38068.42897356597, 42775.35836962386, 46087.0, 47055.76472117617, 47107.76446117885, 47548.2867756977, 11648.09413407145, 11858.762824743284, 11903.40482975865, 11941.16411851162, 11977.700557486132, 12414.751704966031, 12455.501779928702, 12464.43910024033, 12747.80878286845, 12876.420561074961, 11924.523019079139, 11959.820602690792, 12122.696932576757, 12286.631401057983, 12456.875431245571, 11573.0, 11811.704707382385, 11835.171537992257, 11882.227655202385, 12236.449359778653, 11413.265764193091, 11462.083033357145, 11930.940345298219, 11935.164538482222, 12065.443748069287, 10224.079832815083, 10296.227782916161, 10522.947385263025, 10710.11416898206, 10799.645619519037, 9733.56198971062, 10357.0, 10395.0, 10564.577416903237, 10626.946865265934], "unit": "ms", "name": "yui.html", "value": 6216.918060335034}], "name": "dromaeo_css", "value": 6726.2416665878445}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 106.0, 110.0, 113.0, 112.0, 123.0, 110.0, 109.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 111.6}, {"lowerIsBetter": true, "replicates": [153.0, 125.0, 154.0, 158.0, 129.0, 159.0, 158.0, 128.0, 166.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.6}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 161.0, 161.0, 163.0, 160.0, 164.0, 165.0, 170.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [71.0, 74.0, 72.0, 73.0, 73.0, 139.0, 72.0, 73.0, 71.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 79.1}, {"lowerIsBetter": true, "replicates": [90.0, 92.0, 138.0, 91.0, 92.0, 91.0, 143.0, 91.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.0}, {"lowerIsBetter": true, "replicates": [60.0, 63.0, 62.0, 61.0, 63.0, 63.0, 71.0, 62.0, 69.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 63.3}, {"lowerIsBetter": true, "replicates": [69.0, 71.0, 69.0, 70.0, 70.0, 70.0, 69.0, 69.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.7}, {"lowerIsBetter": true, "replicates": [83.0, 92.0, 86.0, 85.0, 86.0, 84.0, 86.0, 86.0, 85.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.7}, {"lowerIsBetter": true, "replicates": [132.0, 127.0, 118.0, 119.0, 128.0, 122.0, 117.0, 124.0, 133.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.7}, {"lowerIsBetter": true, "replicates": [141.0, 153.0, 143.0, 151.0, 151.0, 155.0, 148.0, 156.0, 148.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.9}, {"lowerIsBetter": true, "replicates": [68.0, 66.0, 69.0, 68.0, 67.0, 70.0, 65.0, 69.0, 66.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.8}], "name": "kraken", "value": 1516.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.6929120969062], "unit": "score", "name": "Richards", "value": 215.65991799999992}, {"lowerIsBetter": false, "replicates": [194.25019425019426], "unit": "score", "name": "DeltaBlue", "value": 340.37546399999997}, {"lowerIsBetter": false, "replicates": [284.7380410022779], "unit": "score", "name": "Encrypt", "value": 934.8276720000003}, {"lowerIsBetter": false, "replicates": [5055.555555555556], "unit": "score", "name": "Decrypt", "value": 52.65118681318681}, {"lowerIsBetter": false, "replicates": [1059.322033898305], "unit": "score", "name": "RayTrace", "value": 698.549616}, {"lowerIsBetter": false, "replicates": [701.7543859649123], "unit": "score", "name": "Earley", "value": 949.7097749999995}, {"lowerIsBetter": false, "replicates": [12120.481927710844], "unit": "score", "name": "Boyer", "value": 54.9865099403579}, {"lowerIsBetter": false, "replicates": [25175.0], "unit": "score", "name": "RegExp", "value": 36.186097318768596}, {"lowerIsBetter": false, "replicates": [630.119722747322], "unit": "score", "name": "Splay", "value": 129.326217}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 21344.879045549475}]} 12:52:47 INFO - RETURN: dromaeo_css: 6726.24 12:52:47 INFO - RETURN: kraken: 1516.70 12:52:47 INFO - RETURN: v8_7: 21344.88 12:52:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "21344.88"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1516.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "6726.24"}}} 12:52:47 INFO - Return code: 0 12:52:47 INFO - # TBPL SUCCESS # 12:52:47 INFO - Running post-action listener: _resource_record_post_action 12:52:47 INFO - Running post-run listener: _resource_record_post_run 12:52:48 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 10383360; Write bytes: 399581184; Read time: 2792; Write time: 536028 12:52:48 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 4337664; Read time: 0; Write time: 252 12:52:48 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 8228864; Write bytes: 395243520; Read time: 2664; Write time: 535776 12:52:48 INFO - Running post-run listener: _upload_blobber_files 12:52:48 INFO - Blob upload gear active. 12:52:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:52:48 INFO - Copying logs to upload dir... 12:52:48 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1272.440839 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-11-18 12:52:48.736263) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 12:52:48.739478) ========= 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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010211 build_url: 'https://queue.taskcluster.net/v1/task/zPp0IkguTLOOhI-pgMKuTg/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-18 12:52:48.791812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:52:48.792153) ========= 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=9c54e757a4c805d85fea3bc7000001f2-1447878115.384718-135537918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004407 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 12:52:48.842450) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 12:52:48.842774) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 12:52:48.843066) ========= ========= Total master_lag: 0.19 =========