builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-023 starttime: 1448106395.52 results: success (0) buildid: 20151121013100 builduid: b66ea78946404830994c6e308b0aef25 revision: c3aa84cd334c17606ff33284a058064eafd67d28 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.521917) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.522436) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.522728) ========= 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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006480 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.570146) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.570420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.591461) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.591770) ========= 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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.650387) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.650688) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.651076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.651374) ========= 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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-21 03:46: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2015-11-21 03:46:35 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.211757 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.877019) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.877369) ========= 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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018494 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.928228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:35.928616) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c3aa84cd334c17606ff33284a058064eafd67d28 --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 c3aa84cd334c17606ff33284a058064eafd67d28 --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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-21 03:46:36,060 truncating revision to first 12 chars 2015-11-21 03:46:36,060 Setting DEBUG logging. 2015-11-21 03:46:36,060 attempt 1/10 2015-11-21 03:46:36,060 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c3aa84cd334c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-21 03:46:36,344 unpacking tar archive at: mozilla-inbound-c3aa84cd334c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.593793 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:36.535337) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:36.535638) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:36.542610) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:36.542861) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-21 03:46:36.543283) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2015-11-21 03:46:36.543571) ========= /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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:36 INFO - MultiFileLogger online at 20151121 03:46:36 in /builds/slave/test-pgo 03:46: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 03:46:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 03:46:36 INFO - {'append_to_log': False, 03:46:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 03:46:36 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 03:46:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 03:46:36 INFO - 'branch': 'Mozilla-Inbound', 03:46:36 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:36 INFO - 'config_files': ('talos/linux_config.py',), 03:46:36 INFO - 'default_actions': ('clobber', 03:46:36 INFO - 'read-buildbot-config', 03:46:36 INFO - 'download-and-extract', 03:46:36 INFO - 'populate-webroot', 03:46:36 INFO - 'create-virtualenv', 03:46:36 INFO - 'install', 03:46:36 INFO - 'run-tests'), 03:46:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:36 INFO - 'download_minidump_stackwalk': True, 03:46:36 INFO - 'download_symbols': 'ondemand', 03:46:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:46:36 INFO - 'tooltool.py': '/tools/tooltool.py', 03:46:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:46:36 INFO - '/tools/misc-python/virtualenv.py')}, 03:46:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:36 INFO - 'installer_path': 'installer.exe', 03:46:36 INFO - 'log_level': 'info', 03:46:36 INFO - 'log_name': 'talos', 03:46:36 INFO - 'log_to_console': True, 03:46:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:36 INFO - 'opt_config_files': (), 03:46:36 INFO - 'pip_index': False, 03:46:36 INFO - 'sps_profile': False, 03:46:36 INFO - 'sps_profile_interval': 0, 03:46:36 INFO - 'suite': 'dromaeojs-e10s', 03:46:36 INFO - 'system_bits': '64', 03:46:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:36 INFO - 'title': 'talos-linux64-ix-023', 03:46:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:36 INFO - 'use_talos_json': True, 03:46:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 03:46:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:36 INFO - 'work_dir': 'build'} 03:46:36 INFO - ##### 03:46:36 INFO - ##### Running clobber step. 03:46:36 INFO - ##### 03:46:36 INFO - Running pre-action listener: _resource_record_pre_action 03:46:36 INFO - Running main action method: clobber 03:46:36 INFO - rmtree: /builds/slave/test-pgo/build 03:46:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 03:46:37 INFO - Running post-action listener: _resource_record_post_action 03:46:37 INFO - ##### 03:46:37 INFO - ##### Running read-buildbot-config step. 03:46:37 INFO - ##### 03:46:37 INFO - Running pre-action listener: _resource_record_pre_action 03:46:37 INFO - Running main action method: read_buildbot_config 03:46:37 INFO - Using buildbot properties: 03:46:37 INFO - { 03:46:37 INFO - "properties": { 03:46:37 INFO - "buildnumber": 456, 03:46:37 INFO - "product": "firefox", 03:46:37 INFO - "script_repo_revision": "production", 03:46:37 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 03:46:37 INFO - "repository": "", 03:46:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 03:46:37 INFO - "buildid": "20151121013100", 03:46:37 INFO - "slavename": "talos-linux64-ix-023", 03:46:37 INFO - "pgo_build": "True", 03:46:37 INFO - "basedir": "/builds/slave/test-pgo", 03:46:37 INFO - "project": "", 03:46:37 INFO - "platform": "ubuntu64_hw", 03:46:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:46:37 INFO - "slavebuilddir": "test", 03:46:37 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 03:46:37 INFO - "branch": "mozilla-inbound", 03:46:37 INFO - "repo_path": "integration/mozilla-inbound", 03:46:37 INFO - "stage_platform": "linux64-pgo", 03:46:37 INFO - "builduid": "b66ea78946404830994c6e308b0aef25", 03:46:37 INFO - "revision": "c3aa84cd334c17606ff33284a058064eafd67d28" 03:46:37 INFO - }, 03:46:37 INFO - "sourcestamp": { 03:46:37 INFO - "repository": "", 03:46:37 INFO - "hasPatch": false, 03:46:37 INFO - "project": "", 03:46:37 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 03:46:37 INFO - "changes": [ 03:46:37 INFO - { 03:46:37 INFO - "category": null, 03:46:37 INFO - "files": [ 03:46:37 INFO - { 03:46:37 INFO - "url": null, 03:46:37 INFO - "name": "https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 03:46:37 INFO - } 03:46:37 INFO - ], 03:46:37 INFO - "repository": "", 03:46:37 INFO - "rev": "c3aa84cd334c17606ff33284a058064eafd67d28", 03:46:37 INFO - "who": "sendchange", 03:46:37 INFO - "when": 1448106347, 03:46:37 INFO - "number": 6692238, 03:46:37 INFO - "comments": "", 03:46:37 INFO - "project": "", 03:46:37 INFO - "at": "Sat 21 Nov 2015 03:45:47", 03:46:37 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 03:46:37 INFO - "revlink": "", 03:46:37 INFO - "properties": [ 03:46:37 INFO - [ 03:46:37 INFO - "buildid", 03:46:37 INFO - "20151121013100", 03:46:37 INFO - "Change" 03:46:37 INFO - ], 03:46:37 INFO - [ 03:46:37 INFO - "builduid", 03:46:37 INFO - "b66ea78946404830994c6e308b0aef25", 03:46:37 INFO - "Change" 03:46:37 INFO - ], 03:46:37 INFO - [ 03:46:37 INFO - "pgo_build", 03:46:37 INFO - "True", 03:46:37 INFO - "Change" 03:46:37 INFO - ] 03:46:37 INFO - ], 03:46:37 INFO - "revision": "c3aa84cd334c17606ff33284a058064eafd67d28" 03:46:37 INFO - } 03:46:37 INFO - ], 03:46:37 INFO - "revision": "c3aa84cd334c17606ff33284a058064eafd67d28" 03:46:37 INFO - } 03:46:37 INFO - } 03:46:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 03:46:37 INFO - Running post-action listener: _resource_record_post_action 03:46:37 INFO - ##### 03:46:37 INFO - ##### Running download-and-extract step. 03:46:37 INFO - ##### 03:46:37 INFO - Running pre-action listener: _resource_record_pre_action 03:46:37 INFO - Running main action method: download_and_extract 03:46:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 03:46: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')]} 03:46:37 INFO - https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:46:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/test_packages.json 03:46:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/test_packages.json 03:46:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 03:46:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 03:46:44 INFO - Downloaded 1302 bytes. 03:46:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 03:46:44 INFO - Using the following test package requirements: 03:46:44 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:46:44 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:46:44 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'jsshell-linux-x86_64.zip'], 03:46:44 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:46:44 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:46:44 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:46:44 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:46:44 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:46:44 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:46:44 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:46:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:44 INFO - https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:46:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:46:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:46:50 INFO - Downloaded 22524115 bytes. 03:46:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 03:46:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:46:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:46:51 INFO - Return code: 0 03:46:51 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 03:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:51 INFO - https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:46:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:46:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:46:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:46:54 INFO - Downloaded 22524115 bytes. 03:46:54 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 03:46:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:46:54 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 03:46:55 INFO - Return code: 0 03:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:55 INFO - https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:46:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:46:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:46:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:46:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:47:00 INFO - Downloaded 11130997 bytes. 03:47:00 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 03:47:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:47:00 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 03:47:00 INFO - Return code: 0 03:47:00 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')]} 03:47:00 INFO - https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:47:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:47:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:47:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:47:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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 03:47:16 INFO - Downloaded 57433500 bytes. 03:47:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:47:16 INFO - mkdir: /builds/slave/test-pgo/properties 03:47:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:47:16 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:47:16 INFO - Contents: 03:47:16 INFO - build_url:https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:47:16 INFO - Running post-action listener: _resource_record_post_action 03:47:16 INFO - Running post-action listener: set_extra_try_arguments 03:47:16 INFO - ##### 03:47:16 INFO - ##### Running populate-webroot step. 03:47:16 INFO - ##### 03:47:16 INFO - Running pre-action listener: _resource_record_pre_action 03:47:16 INFO - Running main action method: populate_webroot 03:47:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:47:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:47:16 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 03:47:16 INFO - '../fennec_ids.txt'], 03:47:16 INFO - 'tests': ['tcheck2']}, 03:47:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:47:16 INFO - '--tppagecycles', 03:47:16 INFO - '7'], 03:47:16 INFO - 'tests': ['tsvgm']}}, 03:47:16 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:47:16 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:47:16 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:47:16 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:47:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'tests': ['damp', 'tps']}, 03:47:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['damp', 'tps']}, 03:47:16 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:47:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:47:16 INFO - 'other': {'tests': ['a11yr', 03:47:16 INFO - 'ts_paint', 03:47:16 INFO - 'tpaint', 03:47:16 INFO - 'sessionrestore', 03:47:16 INFO - 'sessionrestore_no_auto_restore']}, 03:47:16 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['a11yr', 03:47:16 INFO - 'ts_paint', 03:47:16 INFO - 'tpaint', 03:47:16 INFO - 'sessionrestore', 03:47:16 INFO - 'sessionrestore_no_auto_restore']}, 03:47:16 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['a11yr', 03:47:16 INFO - 'ts_paint', 03:47:16 INFO - 'tpaint', 03:47:16 INFO - 'sessionrestore', 03:47:16 INFO - 'sessionrestore_no_auto_restore']}, 03:47:16 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['a11yr', 03:47:16 INFO - 'ts_paint', 03:47:16 INFO - 'tpaint', 03:47:16 INFO - 'sessionrestore', 03:47:16 INFO - 'sessionrestore_no_auto_restore']}, 03:47:16 INFO - 'other_l64': {'tests': ['a11yr', 03:47:16 INFO - 'ts_paint', 03:47:16 INFO - 'tpaint', 03:47:16 INFO - 'sessionrestore', 03:47:16 INFO - 'sessionrestore_no_auto_restore']}, 03:47:16 INFO - 'other_nol64': {'tests': ['a11yr', 03:47:16 INFO - 'ts_paint', 03:47:16 INFO - 'tpaint', 03:47:16 INFO - 'sessionrestore', 03:47:16 INFO - 'sessionrestore_no_auto_restore']}, 03:47:16 INFO - 'svgr': {'tests': ['tsvgx', 03:47:16 INFO - 'tsvgr_opacity', 03:47:16 INFO - 'tart', 03:47:16 INFO - 'tscrollx', 03:47:16 INFO - 'cart']}, 03:47:16 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['tsvgx', 03:47:16 INFO - 'tsvgr_opacity', 03:47:16 INFO - 'tart', 03:47:16 INFO - 'tscrollx', 03:47:16 INFO - 'cart']}, 03:47:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'tests': ['tp5o']}, 03:47:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'talos_options': ['--e10s'], 03:47:16 INFO - 'tests': ['tp5o']}, 03:47:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'talos_options': ['--xperf_path', 03:47:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:16 INFO - 'tests': ['tp5n']}, 03:47:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:16 INFO - 'talos_options': ['--e10s', 03:47:16 INFO - '--xperf_path', 03:47:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:16 INFO - 'tests': ['tp5n']}}, 03:47:16 INFO - 'talos.zip': {'path': '', 03:47:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:47:16 INFO - Running post-action listener: _resource_record_post_action 03:47:16 INFO - ##### 03:47:16 INFO - ##### Running create-virtualenv step. 03:47:16 INFO - ##### 03:47:16 INFO - Running pre-action listener: _resource_record_pre_action 03:47:16 INFO - Running main action method: create_virtualenv 03:47:16 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:47:16 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 03:47:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:47:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:47:16 INFO - Using real prefix '/usr' 03:47:16 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:47:17 INFO - Installing distribute.............................................................................................................................................................................................done. 03:47:19 INFO - Installing pip.................done. 03:47:19 INFO - Return code: 0 03:47:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:47: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')]} 03:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47: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')]} 03:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47: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', '--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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:19 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 03:47:19 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 03:47:19 INFO - Using env: {'DISPLAY': ':0', 03:47:19 INFO - 'HOME': '/home/cltbld', 03:47:19 INFO - 'LANG': 'en_US.UTF-8', 03:47:19 INFO - 'LANGUAGE': 'en_US:en', 03:47:19 INFO - 'LOGNAME': 'cltbld', 03:47:19 INFO - 'MAIL': '/var/mail/cltbld', 03:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:19 INFO - 'MOZ_NO_REMOTE': '1', 03:47:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:19 INFO - 'NO_EM_RESTART': '1', 03:47:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:19 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:19 INFO - 'SHELL': '/bin/bash', 03:47:19 INFO - 'SHLVL': '1', 03:47:19 INFO - 'TERM': 'linux', 03:47:19 INFO - 'TMOUT': '86400', 03:47:19 INFO - 'USER': 'cltbld', 03:47:19 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:19 INFO - '_': '/tools/buildbot/bin/python'} 03:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:19 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 03:47:19 INFO - Cleaning up... 03:47:19 INFO - Return code: 0 03:47:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:47: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')]} 03:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47: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')]} 03:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47: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', '--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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:19 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 03:47:19 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 03:47:19 INFO - Using env: {'DISPLAY': ':0', 03:47:19 INFO - 'HOME': '/home/cltbld', 03:47:19 INFO - 'LANG': 'en_US.UTF-8', 03:47:19 INFO - 'LANGUAGE': 'en_US:en', 03:47:19 INFO - 'LOGNAME': 'cltbld', 03:47:19 INFO - 'MAIL': '/var/mail/cltbld', 03:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:19 INFO - 'MOZ_NO_REMOTE': '1', 03:47:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:19 INFO - 'NO_EM_RESTART': '1', 03:47:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:19 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:19 INFO - 'SHELL': '/bin/bash', 03:47:19 INFO - 'SHLVL': '1', 03:47:19 INFO - 'TERM': 'linux', 03:47:19 INFO - 'TMOUT': '86400', 03:47:19 INFO - 'USER': 'cltbld', 03:47:19 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:19 INFO - '_': '/tools/buildbot/bin/python'} 03:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:19 INFO - Downloading/unpacking psutil>=0.7.1 03:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:21 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 03:47:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:47:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:47:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:21 INFO - Installing collected packages: psutil 03:47:21 INFO - Running setup.py install for psutil 03:47:21 INFO - building 'psutil._psutil_linux' extension 03:47:21 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 03:47:21 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 03:47:21 INFO - building 'psutil._psutil_posix' extension 03:47:21 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 03:47:21 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 03:47:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:21 INFO - Successfully installed psutil 03:47:21 INFO - Cleaning up... 03:47:21 INFO - Return code: 0 03:47:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:21 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 03:47:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --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 03:47:21 INFO - Using env: {'DISPLAY': ':0', 03:47:21 INFO - 'HOME': '/home/cltbld', 03:47:21 INFO - 'LANG': 'en_US.UTF-8', 03:47:21 INFO - 'LANGUAGE': 'en_US:en', 03:47:21 INFO - 'LOGNAME': 'cltbld', 03:47:21 INFO - 'MAIL': '/var/mail/cltbld', 03:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:21 INFO - 'MOZ_NO_REMOTE': '1', 03:47:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:21 INFO - 'NO_EM_RESTART': '1', 03:47:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:21 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:21 INFO - 'SHELL': '/bin/bash', 03:47:21 INFO - 'SHLVL': '1', 03:47:21 INFO - 'TERM': 'linux', 03:47:21 INFO - 'TMOUT': '86400', 03:47:21 INFO - 'USER': 'cltbld', 03:47:21 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:21 INFO - '_': '/tools/buildbot/bin/python'} 03:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:47:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:47:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:47:23 INFO - Installing collected packages: mozsystemmonitor 03:47:23 INFO - Running setup.py install for mozsystemmonitor 03:47:23 INFO - Successfully installed mozsystemmonitor 03:47:23 INFO - Cleaning up... 03:47:23 INFO - Return code: 0 03:47:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:23 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:23 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 03:47:23 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 03:47:23 INFO - Using env: {'DISPLAY': ':0', 03:47:23 INFO - 'HOME': '/home/cltbld', 03:47:23 INFO - 'LANG': 'en_US.UTF-8', 03:47:23 INFO - 'LANGUAGE': 'en_US:en', 03:47:23 INFO - 'LOGNAME': 'cltbld', 03:47:23 INFO - 'MAIL': '/var/mail/cltbld', 03:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:23 INFO - 'MOZ_NO_REMOTE': '1', 03:47:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:23 INFO - 'NO_EM_RESTART': '1', 03:47:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:23 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:23 INFO - 'SHELL': '/bin/bash', 03:47:23 INFO - 'SHLVL': '1', 03:47:23 INFO - 'TERM': 'linux', 03:47:23 INFO - 'TMOUT': '86400', 03:47:23 INFO - 'USER': 'cltbld', 03:47:23 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:23 INFO - '_': '/tools/buildbot/bin/python'} 03:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:24 INFO - Downloading/unpacking blobuploader==1.2.4 03:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:25 INFO - Downloading blobuploader-1.2.4.tar.gz 03:47:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:47:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:47:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:47:25 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 03:47:25 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 03:47:25 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 03:47:25 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 03:47:25 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 03:47:25 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 03:47:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:47:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:47:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:47:26 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 03:47:26 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 03:47:26 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 03:47:26 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 03:47:26 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 03:47:26 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 03:47:26 INFO - Downloading docopt-0.6.1.tar.gz 03:47:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:47:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:47:27 INFO - Installing collected packages: blobuploader, requests, docopt 03:47:27 INFO - Running setup.py install for blobuploader 03:47:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:47:27 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:47:27 INFO - Running setup.py install for requests 03:47:27 INFO - Running setup.py install for docopt 03:47:27 INFO - Successfully installed blobuploader requests docopt 03:47:27 INFO - Cleaning up... 03:47:27 INFO - Return code: 0 03:47:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:27 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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:27 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 03:47:27 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 03:47:27 INFO - Using env: {'DISPLAY': ':0', 03:47:27 INFO - 'HOME': '/home/cltbld', 03:47:27 INFO - 'LANG': 'en_US.UTF-8', 03:47:27 INFO - 'LANGUAGE': 'en_US:en', 03:47:27 INFO - 'LOGNAME': 'cltbld', 03:47:27 INFO - 'MAIL': '/var/mail/cltbld', 03:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:27 INFO - 'MOZ_NO_REMOTE': '1', 03:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:27 INFO - 'NO_EM_RESTART': '1', 03:47:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:27 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:27 INFO - 'SHELL': '/bin/bash', 03:47:27 INFO - 'SHLVL': '1', 03:47:27 INFO - 'TERM': 'linux', 03:47:27 INFO - 'TMOUT': '86400', 03:47:27 INFO - 'USER': 'cltbld', 03:47:27 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:27 INFO - '_': '/tools/buildbot/bin/python'} 03:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:47:28 INFO - Running setup.py (path:/tmp/pip-4QJDMU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:47:28 INFO - Running setup.py (path:/tmp/pip-GcMxSC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:47:28 INFO - Running setup.py (path:/tmp/pip-ksq6bh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:47:28 INFO - Running setup.py (path:/tmp/pip-GChbJX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:47:28 INFO - Running setup.py (path:/tmp/pip-Se6JUF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:47:28 INFO - Running setup.py (path:/tmp/pip-bcD25q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:47:28 INFO - Running setup.py (path:/tmp/pip-suNAru-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:47:28 INFO - Running setup.py (path:/tmp/pip-e7MpRg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:47:28 INFO - Running setup.py (path:/tmp/pip-DFQkzL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:47:28 INFO - Running setup.py (path:/tmp/pip-r2S2YZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:47:28 INFO - Running setup.py (path:/tmp/pip-wmbqc2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:47:28 INFO - Running setup.py (path:/tmp/pip-PDavzP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:47:28 INFO - Running setup.py (path:/tmp/pip-lmc3NN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:47:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:47:28 INFO - Running setup.py (path:/tmp/pip-G7IkNS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:47:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:47:29 INFO - Running setup.py (path:/tmp/pip-2QwpoE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:47:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:47:29 INFO - Running setup.py (path:/tmp/pip-Xn6LYt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:47:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:47:29 INFO - Running setup.py (path:/tmp/pip-v_S6dv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:47:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:47:29 INFO - Running setup.py install for manifestparser 03:47:29 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:47:29 INFO - Running setup.py install for mozcrash 03:47:29 INFO - Running setup.py install for mozdebug 03:47:29 INFO - Running setup.py install for mozdevice 03:47:29 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:47:29 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:47:29 INFO - Running setup.py install for mozfile 03:47:29 INFO - Running setup.py install for mozhttpd 03:47:29 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:47:29 INFO - Running setup.py install for mozinfo 03:47:29 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:47:29 INFO - Running setup.py install for mozInstall 03:47:30 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Running setup.py install for mozleak 03:47:30 INFO - Running setup.py install for mozlog 03:47:30 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Running setup.py install for moznetwork 03:47:30 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Running setup.py install for mozprocess 03:47:30 INFO - Running setup.py install for mozprofile 03:47:30 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Running setup.py install for mozrunner 03:47:30 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:47:30 INFO - Running setup.py install for mozscreenshot 03:47:30 INFO - Running setup.py install for moztest 03:47:30 INFO - Running setup.py install for mozversion 03:47:31 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:47:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:47:31 INFO - Cleaning up... 03:47:31 INFO - Return code: 0 03:47:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:47:31 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')]} 03:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:31 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')]} 03:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:31 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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:31 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 03:47:31 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 03:47:31 INFO - Using env: {'DISPLAY': ':0', 03:47:31 INFO - 'HOME': '/home/cltbld', 03:47:31 INFO - 'LANG': 'en_US.UTF-8', 03:47:31 INFO - 'LANGUAGE': 'en_US:en', 03:47:31 INFO - 'LOGNAME': 'cltbld', 03:47:31 INFO - 'MAIL': '/var/mail/cltbld', 03:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:31 INFO - 'MOZ_NO_REMOTE': '1', 03:47:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:31 INFO - 'NO_EM_RESTART': '1', 03:47:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:31 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:31 INFO - 'SHELL': '/bin/bash', 03:47:31 INFO - 'SHLVL': '1', 03:47:31 INFO - 'TERM': 'linux', 03:47:31 INFO - 'TMOUT': '86400', 03:47:31 INFO - 'USER': 'cltbld', 03:47:31 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:31 INFO - '_': '/tools/buildbot/bin/python'} 03:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:47:31 INFO - Running setup.py (path:/tmp/pip-_mhDm3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:47:31 INFO - Running setup.py (path:/tmp/pip-bwgPKl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:47:31 INFO - Running setup.py (path:/tmp/pip-IoEJnn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:47:31 INFO - Running setup.py (path:/tmp/pip-AtJNCa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:47:31 INFO - Running setup.py (path:/tmp/pip-17VBvk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:47:31 INFO - Running setup.py (path:/tmp/pip-Jw_0XV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:47:31 INFO - Running setup.py (path:/tmp/pip-EckuMx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:47:31 INFO - Running setup.py (path:/tmp/pip-Amba53-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:47:31 INFO - Running setup.py (path:/tmp/pip-jKl0W1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:47:31 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)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:47:31 INFO - Running setup.py (path:/tmp/pip-nKZJiw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:47:31 INFO - Running setup.py (path:/tmp/pip-jdozJe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:47:32 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)) 03:47:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:47:32 INFO - Running setup.py (path:/tmp/pip-es4iEV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:47:32 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)) 03:47:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:47:32 INFO - Running setup.py (path:/tmp/pip-X0Gp1a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:47:32 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)) 03:47:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:47:32 INFO - Running setup.py (path:/tmp/pip-8L8qnB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:47:32 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)) 03:47:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:47:32 INFO - Running setup.py (path:/tmp/pip-DaErRX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:47:32 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)) 03:47:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:47:32 INFO - Running setup.py (path:/tmp/pip-AMw01U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:47:32 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)) 03:47:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:47:32 INFO - Running setup.py (path:/tmp/pip-Se223V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:47:32 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)) 03:47:32 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)) 03:47:32 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)) 03:47:32 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)) 03:47:32 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)) 03:47:32 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)) 03:47:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:32 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 03:47:32 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 03:47:32 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 03:47:32 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 03:47:32 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 03:47:32 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 03:47:34 INFO - Downloading blessings-1.5.1.tar.gz 03:47:34 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 03:47:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:47:34 INFO - Installing collected packages: blessings 03:47:34 INFO - Running setup.py install for blessings 03:47:34 INFO - Successfully installed blessings 03:47:34 INFO - Cleaning up... 03:47:34 INFO - Return code: 0 03:47:34 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:47:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:47:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:47:34 INFO - Reading from file tmpfile_stdout 03:47:34 INFO - Current package versions: 03:47:34 INFO - argparse == 1.2.1 03:47:34 INFO - blessings == 1.5.1 03:47:34 INFO - blobuploader == 1.2.4 03:47:34 INFO - docopt == 0.6.1 03:47:34 INFO - manifestparser == 1.1 03:47:34 INFO - mozInstall == 1.12 03:47:34 INFO - mozcrash == 0.16 03:47:34 INFO - mozdebug == 0.1 03:47:34 INFO - mozdevice == 0.47 03:47:34 INFO - mozfile == 1.2 03:47:34 INFO - mozhttpd == 0.7 03:47:34 INFO - mozinfo == 0.9 03:47:34 INFO - mozleak == 0.1 03:47:34 INFO - mozlog == 3.1 03:47:34 INFO - moznetwork == 0.27 03:47:34 INFO - mozprocess == 0.22 03:47:34 INFO - mozprofile == 0.27 03:47:34 INFO - mozrunner == 6.11 03:47:34 INFO - mozscreenshot == 0.1 03:47:34 INFO - mozsystemmonitor == 0.0 03:47:34 INFO - moztest == 0.7 03:47:34 INFO - mozversion == 1.4 03:47:34 INFO - psutil == 3.1.1 03:47:34 INFO - requests == 1.2.3 03:47:34 INFO - wsgiref == 0.1.2 03:47:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:47: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')]} 03:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47: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')]} 03:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:34 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 0x7f7f089d5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25db6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, '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': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', '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 03:47:34 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 03:47:34 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 03:47:34 INFO - Using env: {'DISPLAY': ':0', 03:47:34 INFO - 'HOME': '/home/cltbld', 03:47:34 INFO - 'LANG': 'en_US.UTF-8', 03:47:34 INFO - 'LANGUAGE': 'en_US:en', 03:47:34 INFO - 'LOGNAME': 'cltbld', 03:47:34 INFO - 'MAIL': '/var/mail/cltbld', 03:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:34 INFO - 'MOZ_NO_REMOTE': '1', 03:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:34 INFO - 'NO_EM_RESTART': '1', 03:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:34 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:34 INFO - 'SHELL': '/bin/bash', 03:47:34 INFO - 'SHLVL': '1', 03:47:34 INFO - 'TERM': 'linux', 03:47:34 INFO - 'TMOUT': '86400', 03:47:34 INFO - 'USER': 'cltbld', 03:47:34 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:34 INFO - '_': '/tools/buildbot/bin/python'} 03:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 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)) 03:47:34 INFO - Cleaning up... 03:47:34 INFO - Return code: 0 03:47:34 INFO - Running post-action listener: _resource_record_post_action 03:47:34 INFO - Running post-action listener: _start_resource_monitoring 03:47:34 INFO - Starting resource monitoring. 03:47:34 INFO - ##### 03:47:34 INFO - ##### Running install step. 03:47:34 INFO - ##### 03:47:34 INFO - Running pre-action listener: _resource_record_pre_action 03:47:34 INFO - Running main action method: install 03:47:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:47:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:47:35 INFO - Reading from file tmpfile_stdout 03:47:35 INFO - Detecting whether we're running mozinstall >=1.0... 03:47:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:47:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:47:35 INFO - Reading from file tmpfile_stdout 03:47:35 INFO - Output received: 03:47:35 INFO - Usage: mozinstall [options] installer 03:47:35 INFO - Options: 03:47:35 INFO - -h, --help show this help message and exit 03:47:35 INFO - -d DEST, --destination=DEST 03:47:35 INFO - Directory to install application into. [default: 03:47:35 INFO - "/builds/slave/test-pgo"] 03:47:35 INFO - --app=APP Application being installed. [default: firefox] 03:47:35 INFO - mkdir: /builds/slave/test-pgo/build/application 03:47:35 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'] 03:47:35 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 03:47:49 INFO - Reading from file tmpfile_stdout 03:47:49 INFO - Output received: 03:47:49 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:47:49 INFO - Running post-action listener: _resource_record_post_action 03:47:49 INFO - ##### 03:47:49 INFO - ##### Running run-tests step. 03:47:49 INFO - ##### 03:47:49 INFO - Running pre-action listener: _resource_record_pre_action 03:47:49 INFO - Running main action method: run_tests 03:47:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:47:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:47:49 INFO - Python 2.7.3 03:47:49 INFO - Return code: 0 03:47:49 INFO - grabbing minidump binary from tooltool 03:47:49 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')]} 03:47:49 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 0x26d1d70>, '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 0x26d2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d2fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:47:49 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 03:47:49 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 03:47:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:49 INFO - Return code: 0 03:47:49 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:47:49 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:47:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:47:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:47:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:47:49 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/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 03:47:49 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/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost 03:47:49 INFO - Using env: {'DISPLAY': ':0', 03:47:49 INFO - 'HOME': '/home/cltbld', 03:47:49 INFO - 'LANG': 'en_US.UTF-8', 03:47:49 INFO - 'LANGUAGE': 'en_US:en', 03:47:49 INFO - 'LOGNAME': 'cltbld', 03:47:49 INFO - 'MAIL': '/var/mail/cltbld', 03:47:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:47:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:49 INFO - 'MOZ_NO_REMOTE': '1', 03:47:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:47:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:49 INFO - 'NO_EM_RESTART': '1', 03:47:49 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:49 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:49 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:47:49 INFO - 'SHELL': '/bin/bash', 03:47:49 INFO - 'SHLVL': '1', 03:47:49 INFO - 'TERM': 'linux', 03:47:49 INFO - 'TMOUT': '86400', 03:47:49 INFO - 'USER': 'cltbld', 03:47:49 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419', 03:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:49 INFO - '_': '/tools/buildbot/bin/python'} 03:47:49 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/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost'] with output_timeout 3600 03:47:49 INFO - mozversion INFO | application_buildid: 20151121013100 03:47:49 INFO - mozversion INFO | application_changeset: c3aa84cd334c17606ff33284a058064eafd67d28 03:47:49 INFO - mozversion INFO | application_display_name: Nightly 03:47:49 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:49 INFO - mozversion INFO | application_name: Firefox 03:47:49 INFO - mozversion INFO | application_remotingname: firefox 03:47:49 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:47:49 INFO - mozversion INFO | application_vendor: Mozilla 03:47:49 INFO - mozversion INFO | application_version: 45.0a1 03:47:49 INFO - mozversion INFO | platform_buildid: 20151121013100 03:47:49 INFO - mozversion INFO | platform_changeset: c3aa84cd334c17606ff33284a058064eafd67d28 03:47:49 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:47:49 INFO - mozversion INFO | platform_version: 45.0a1 03:47:49 INFO - 2015-11-21 03:47:49,852 DEBUG : using testdate: 1448106469 03:47:49 INFO - 2015-11-21 03:47:49,852 DEBUG : actual date: 1448106469 03:47:49 INFO - 2015-11-21 03:47:49,861 INFO : starting webserver on 'localhost:52360' 03:47:49 INFO - 2015-11-21 03:47:49,862 INFO : Starting test suite talos-linux64-ix-023.e 03:47:49 INFO - 2015-11-21 03:47:49,862 INFO : Starting test dromaeo_css 03:47:49 INFO - 2015-11-21 03:47:49,862 DEBUG : operating with platform_type : linux_ 03:47:49 INFO - 2015-11-21 03:47:49,863 INFO : Initialising browser for dromaeo_css test... 03:47:49 INFO - 2015-11-21 03:47:49,877 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVM3am9/profile http://localhost:52360/getInfo.html 03:47:55 INFO - 2015-11-21 03:47:55,489 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:47:55 INFO - 2015-11-21 03:47:55,489 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:47:55 INFO - 2015-11-21 03:47:55,489 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:47:55 INFO - 2015-11-21 03:47:55,490 DEBUG : BROWSER_OUTPUT: __metrics 03:47:56 INFO - 2015-11-21 03:47:56,592 INFO : Browser initialized. 03:47:56 INFO - 2015-11-21 03:47:56,593 INFO : Running cycle 1/1 for dromaeo_css test... 03:47:56 INFO - 2015-11-21 03:47:56,593 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVM3am9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:48:08 INFO - 2015-11-21 03:48:08,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 141193216 03:48:08 INFO - 2015-11-21 03:48:08,373 DEBUG : BROWSER_OUTPUT: 03:48:57 INFO - 2015-11-21 03:48:57,994 DEBUG : BROWSER_OUTPUT: 1448106537992 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] 03:51:07 INFO - 2015-11-21 03:51:07,481 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52360/tests/dromaeo/cssquery-ext.html) 03:51:08 INFO - 2015-11-21 03:51:08,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 147111936 03:51:08 INFO - 2015-11-21 03:51:08,659 DEBUG : BROWSER_OUTPUT: 03:54:10 INFO - 2015-11-21 03:54:10,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/dromaeo/cssquery-ext.html (next: http://localhost:52360/tests/dromaeo/cssquery-jquery.html) 03:54:11 INFO - 2015-11-21 03:54:11,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 141537280 03:54:11 INFO - 2015-11-21 03:54:11,284 DEBUG : BROWSER_OUTPUT: 03:57:12 INFO - 2015-11-21 03:57:12,981 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52360/tests/dromaeo/cssquery-mootools.html) 03:57:13 INFO - 2015-11-21 03:57:13,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 142106624 03:57:13 INFO - 2015-11-21 03:57:13,563 DEBUG : BROWSER_OUTPUT: 04:00:15 INFO - 2015-11-21 04:00:15,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52360/tests/dromaeo/cssquery-prototype.html) 04:00:15 INFO - 2015-11-21 04:00:15,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 139608064 04:00:15 INFO - 2015-11-21 04:00:15,830 DEBUG : BROWSER_OUTPUT: 04:03:18 INFO - 2015-11-21 04:03:18,135 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52360/tests/dromaeo/cssquery-yui.html) 04:03:18 INFO - 2015-11-21 04:03:18,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 140787712 04:03:18 INFO - 2015-11-21 04:03:18,724 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,119 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/dromaeo/cssquery-yui.html (next: http://localhost:52360/tests/dromaeo/cssquery-dojo.html) 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 143986688 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3256.2347848255654;3348.2633820559486;3365.478350855615;3380.8309584520775;3392.694657480826;5470.507654311111;5629.943700562998;5692.402297758727;5707.08686610143;5713.371529131798;5564.666120032801;5588.497035266825;5611.961787069395;5644.350899646542;5674.31908171019;5047.84856454306;5076.517730815566;5208.635395522315;5249.921251181236;5314.229436731672;5242.475752424746;5251.921221181686;5273.209018647195;5300.575953923677;5332.813351532697;5023.648344615878;5058.443571207183;5119.923201151985;5156.896862062757;5158.92261616076;5094.6688465249645;5114.616403769732;5121.82073627425;5121.923171152435;5126.726392299972;5087.262346959707;5101.540861322462;5110.233464980245;5119.411267704188;5125.077486052509;5211.921821172686;5283.577313814885;5352.116900711378;5390.460953904618;5412.864678383033;6134.447899689005;6165.537584681122;6176.969115154396;6178;6210.1616281801835;5836.212111364954;6189.288231853305;6216.533759968021;6220.191375121205;6244.4067813558095;5642.661440313554;5684.4315568443235;5717.628354156966;5740.052891272977;5791.189233507313;6183.81448556544;6247.875042499125;6260.373962603794;6261.436470717657;6281.623102613858;5787.797427090031;5851.824445266564;6044.274687037584;6050.969745151334;6056.3338032816355;5862.237909071793;5974.044152935509;5985.371535988746;5988.04191329394;5991.820245392645;5948.375420580864;6081.969590152021;6091.482224011009;6107.84730381744;6111.694415279308;5862.472377486047;5944.910826337608;5992.970035149797;6065.848353791102;6091.238595175603;4503.932441013388;4507.549245075466;4525.072360166158;4551.271796512542;4567.977160114178;4027.4416864163654;4035.7174997749876;4038.9394159087637;4079.653229475528;4085.611866872642;5564.86087847799;5676.801311954062;5682.857928551819;5730.8280751576685;5873.060310350324;6021.879562408727;6053.545984051214;6070.362611925772;6101.60339577926;6107.083937409424;5723.685197314105;5734.856628584319;5735.139729040594;5744.224529688522;5773.24947756789;5078.5937125029905;5133.332666753298;5159.071367153873;5216;5256.395514515803;6204;6274.1843560337775;6317.842053948596;6331.588446750947;6340.270868850049;6034.034554471264;6172.321044685171;6198.380161983766;6207.565470417027;6212.844678883064;6107.219158344477;6111.470193407107;6194.318624951342;6195.2255968004;6222.906656400067;5934.57061343777;6003.279606447254;6038;6046.455818976313;6074.210352654127;5938.346781854079;6008;6133.049377346519;6161;6166.1983942087245;5897.3807750185615;6003.099535069775;6003.42967418112;6003.939960600338;6046;5979.850503737442;6041.124037014521;6041.78853740117;6042.848928776816;6058.787942421994;5692.117721753135;5764.567657425626;5805.82582522525;5949.553783466171;5951.077582974646;5840.591158619026;5881.647101173943;5887.8233652990475;5953.404659534012;6003.6397816131175;5641.774329026957;5815.127730840408;5885.411458854252;5931.6441013539325;5969.492593129633 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: |1;ext.html;573.6328749600257;580.3355158343696;584.5995493087236;588.8410129265097;623.2676604989136;37107.25785484292;37999.43000854989;38104.61895381045;38159.618403815955;38221.808890955544;40175.59824401755;40655;40812.38781418281;40853.795731021375;40894;8567.443116197443;8702.738917832476;8715.433496822687;8731.388802783807;8754.168354006359;8560.529170895597;8686.782830429258;8693.7826554336;8697.086805885387;8698.304135669163;8597.785055373603;8736.956315218447;8738.213560779528;8739.038705742363;8742.213200811926;8519.872201916976;8662;8662.480251184948;8664.046954834963;8690;8573.099824518398;8705.651773929036;8710.172533609297;8718.21536061751;8731.781705457352;36954;37056.814715926244;37118.81440592807;37220.44169337462;37262.441063384074;3132.3578666373564;3159.6208454985317;3229.660885607001;3308.602967643874;3837.963749787542;29137.41725165485;29183.56224656632;29386.265343366373;29390.70609293901;29897.850510747525;3736.75711078779;3881.7670939743707;3885.4560361549125;3937.9015524611827;4076.6942479313875;256925;259240;259380;259748;260065;51360.74319628378;53092.734536327844;59318;59434.70282648646;59653.40346596479;70275;71248.64375678089;71273;71396;71515;14969.775453368207;15160.545183644288;15260.31328590216;15307.693846123015;15339.233038348038;10852.348859068481;10949.069329106957;10971.780564388668;11036.282641628268;11062.778744425228;20214.079662432046;20651.58696826055;20679.689804652942;20744.89627551883;20751.169953202007;8137.633806478722;8399.580020998925;8444;8454.915450845536;8548.914510854936;894.9552522373986;903.4624398482893;984.6799790068256;1003.2676146413013;1015.720676813882;27763.167104986882;31445.685543144275;32434;32785.50821737676;32980.83509582437;1036.08306648615;1045.38844775807;1099.4997276239292;1131.2567024964428;1145.0953746540306;13771.242581657905;14319.85680143206;14562.490312839;14596.562103136923;14796.70406591862;387336;389814;394642;394926;395146;50000.24999625009;50271.74864125729;50289.24566131511;50350.24474632883;50370.24444633334;1308.8952883769275;1313.835770528684;1323.9669008274677;1331.2811082015796;1333.4066340478394;26360.34099147537;26955.595666064633;27429.725702743515;27625;27734.583981239895;43067;43107.56892431161;43127.35308970431;43354.56645433505;43692;288216;289154;289272;289455;290041;31231;44739.55260447354;44820.77589612162;45569.77215114036;45735.771321143184;42862.57137428586;43092.35361469643;43435.34846977361;43478;43727.34408983804;36374;36751.26497470089;37333.81333093317;37427.251454971294;41277;36630.08424789402;37104;37159;37765.24469510649;37943.62056379401;35503.46744798882;35696.64303357037;35835.462468063524;35998;36244.27511449809;32140.19649508781;32406.83796581002;33073.669263307056;33251;33403.49894751532;25986.480270394863;27590.448191035666;27713.86143069272;27785.166445006682;27986.16041518676 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: |2;jquery.html;351.2150343981202;355.09273805427125;355.3674459462158;360.0799956112134;360.85385418905344;16468.670626587475;16970.575735606602;17635.206415711276;17816.1982710778;18349.633007339846;15996;16088.1955902205;16156.919215403906;16175.595610109753;16530;4712.929306060403;4841.6852904561265;4846.781894814724;4858.125537403266;4871.415430148387;4789.257665061904;4806.182948898678;4839.927401088987;4840.6369522285795;4929.162042452774;3687.3178461984485;3817.0838998640406;5258.50044245796;5519.448055194469;5555.833325000256;5297.894042119136;5327.46725327468;5354.330708661418;5374.677519348832;5375.0593646111965;4711.858644240679;5107.923381149286;5151.613628977804;5435.537979271767;5458.263134476834;11853.762924741457;11912.166148369617;12629.179103358252;20686.172553098007;20862.16551337945;1985.2356842615552;2069.793020697933;2102.8212601928853;2130.5845360154776;2263.7057182566327;9296.95351523238;9323.673671421468;12713.110082294243;13502.527411540548;14144.646383840487;1895.1187697720627;1934.555052337956;1980.1188471130354;2019.1822311963679;2031.7765045844944;58807;62737;63640.68179659072;63685;64059;15587.766183507256;15841.683166336608;15847.366105355888;15904.602384940468;15978.280977356048;24094.349621541616;24345.878270608533;24604.630930536056;24698.259052228103;24751.25746227616;8656.48061116335;8929.910700892908;8951.283897288202;8990.820183596292;9003.549822508849;7606.467547271748;7614.086309642878;7616.695332186761;7631.961840190875;7655.311022008046;7959.283664470109;7968.203179681986;7980.3615710743;8101.270885620323;8193.09875913649;3114.5617495069437;3133.85897634607;3175;3199.2641692410875;3247.220667039893;369.7411811731798;370.77568071317006;927.096081320707;982.4989255479757;983.5918093990914;10172.135368493613;10294.227932905062;10497;10524.631637892637;10589.15286777056;391.0516996284;395.6281095769967;396.5261512492544;397.3272522679494;397.6242450883945;5545.584081193846;5587.1339942309005;5634.1830434586755;5665.121906104478;5677.2619559457835;377575;384524;387568;388711;392181;42620.78689606532;42629.36055959163;47071.29393059107;70527;71795;1109.3288560420829;1126.5324890170486;1131.1177281720436;1142.737170450801;1145.2269717940358;15881.52355429339;15932.20338983023;15957.202139893192;16035;16218.756718648994;34507;34695.479567805996;36840.26319473649;37005.44491832571;37558.8122059388;43020;46262.53737462583;46460.53539464697;46617;46728.532714673776;8836.867446988428;9241.676541321021;9795.755106122404;9918.454485003105;9943.801123977624;10632.52153653072;10706.62526811589;10953.780924381626;18018.72971905396;18169.182386792363;14570.562883113524;14864.182469963824;15010.399584016517;15089;15238.542843714706;13310.534131305358;13416.932915335361;13515.459381624625;13670.931645342107;14476.420943162577;13592.252426116658;13969.092009019381;14035.368408421442;14842.40630374773;14902.105873647615;8639.222469977733;8753.47479151253;8830.205281524693;8896.733098007071;9104.226140778108;9497.762555936157;11002.669919902415;13956.930215348859;14061.086029408465;14304.856951430353 04:06:21 INFO - 2015-11-21 04:06:21,378 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1513.7275290447728;1639.1886016421872;1711.7774689290395;1735.9392421265259;1763.2065570493273;6729.32706729328;6845.178578570579;7759.495632783865;7904.762857114289;7936.682532698699;7354.6322683865865;7493.288137626931;7533.472656914005;7535.171131175566;7555.546667199972;2235.24001839375;2276.4877902471976;2280.1905323610126;2281.942951426219;2286.6112760830697;2261.8416710830247;2279.6466547685136;2292.747797742247;2299.3446867642724;2301.7468078511433;2283.041122728458;2285.2344464604375;2300.6779050932882;2300.884955752214;2330.9650355244685;2231.7098777158944;2240.5406891587186;2244.7194100737406;2263.20787724296;2285.040283081093;2279.3048120323297;2326.220716060122;2328.7904088631935;2343.4492894169857;2346.366481050124;7783.066032076187;7820.296173344369;7848.9607551962445;7872.921270787333;7908.248716371935;4742.407199100113;4803.663743537953;4810.5670489656095;4832.347633069526;4854.12625727369;3026.3493348929865;3065.049834551296;3093.566900633913;3137.247060705437;3143.0413723814227;986.4574484033825;991.5934466868622;1766.4612293250555;1842.8617853660894;1924.6728056230475;10619.20355973305;10673.573057077707;10708.08981236588;10772.4613769312;10786.514606842711;4176.1230141669985;4187.371894215892;4217.261979153697;4225.4506914101585;4315.913681726348;8811;8845.071267516947;8851.955740221258;8883.955580222058;8917.331200159884;4733.928991065137;4776.068666609977;4783.4692898272915;4788.329633851264;4794.7362895040405;4217.325227963511;4317.676174286941;4334.696571239983;4344.565543445693;4374.387585737999;3647.507586475792;3655.214128962286;3669.192777588926;3689.114612493036;3689.538807649044;1156.3293289891842;1159.1248607301602;1160.5647882044234;1164.8602167739757;1171.8769529199483;1335.105479328852;1352.0738294268458;1359.238826257299;1372.4853180057478;1381.4405165907824;7341.669624866894;7419.925800741924;7445.478816482902;7573.962130189314;7574.886376704354;483.7484508055791;493.23302264978304;493.6618416384788;502.52762403340745;504.1857400298565;5599.160125981135;5667.971660141673;5674.2623458950075;5678.375378708339;5713.200151978726;9129.954350228207;9552.37909535878;9604.231661467065;9654.75863103428;9686.70939871805;9084.954575227082;9085.77285567853;9157.633694652273;9221.723348299562;9289;7049.68276427562;7121.501494895411;7192;7210.459215558854;7212.783616491513;6983.9650801745665;6984.511084224056;7007.649617519206;7065;7065.046218760607;8051.758447246592;8110.472819266729;8328.791780205544;8341.95829020851;8362.623681934449;8416.83166336682;8440.324774018187;8492.320614350961;8540.572971351283;8547.786305342415;6877.793666190022;6879.484038697218;6946.895796563157;7035.085438892911;7074.115735533059;7607.543547387175;7628.961855190689;7629;7643.923560764321;7669.808254793452;6950.721971121301;7091.645417729196;7146.32109949554;7155;7365.042544469185;7025.262347453444;7034;7068.399186069241;7158.821029474305;7260.96369518149;6847.691853866688;6859.451243900576;6929.02993580889;6961.686724097326;6967.7212911482975;6337.271213810471;6396.840078998062;6428.646424446513;6437.485001199986;6464.935350646433;6143;6160.414760597736;6244.250689917239;6248.875022499434;6267 04:06:21 INFO - 2015-11-21 04:06:21,379 DEBUG : BROWSER_OUTPUT: |4;prototype.html;297.6175614335579;304.69987062743206;305.0269639848882;306.19318096814885;306.3811101574819;6220.502359811216;6254.093156492306;6293.37066293371;6373.681315934208;6378.266499352576;6097.756089756415;6150.384961503847;6242.750289988407;6255.593386429879;6264.21697287839;4636.86089417318;4674.883127921812;4718.103560323545;4726.905461890778;4729.858104256869;4712.670113092085;4713.670043096985;4740.5970492508;4767.404074490689;4782.83260085897;4621.29414032953;4626.398568186131;4705.388299521058;4725.834595789148;4734.502877197896;4712.670113092085;4713.646476514258;4714.6464015199;4718.103560323528;4721.527847215285;4094.222097801438;4095.631393174599;4110.547839737656;4113.136241389312;4118.279301122321;5980.611260268069;5989.071693887455;5993.970030149865;6011.338752737195;6027.427394397525;5241.371035475768;5402.486763757437;5414.40441551429;5414.729263536808;5500.669959802424;6662.900056499157;6679.432248258904;6687.76592819249;6689.297623749533;6689.531732778707;4382.978085109586;4384.517703052662;4390.868273951786;4395.780210989437;4408.338749187616;6783.796486105425;6962.597903723207;7018.613976231305;7021.368076873106;7037.085178926758;4516.638668906479;4535.297028960516;4545.431821022372;4545.454545454567;4552.226121559276;4546.659000574904;4567.977160114178;4577.8397756078375;4586.816527338936;4619.584237418581;5374.086405311106;5383.031054410244;5386.730663466811;5400.972995134999;5402.810901618424;6623.337666233435;6714.429273511709;6765.357291057342;6774.864502709918;6777.49168812341;1588.1582761136617;1590;1626.1381467822073;1627.8046634403947;1630.4537979776671;1037.3153718545723;1042.0673497220098;1060.1783617696346;1063.2344711807486;1068.4924660786219;6308.337624549448;6337.524685648503;6365.745370185234;6380.649064301508;6388.456981156561;6862.759803406857;6884.965575172192;6885.931140688629;6890.483213758988;6899.6205208713;6167.784127555604;6321.905171422341;6342.39747224013;6377;6389.073584330339;3728.12389088564;3749.6812770914776;3752.6997840173267;3753.8123093845197;3764.0589852536864;7044.92955070453;7053.259407762213;7061.293870612898;7063.823404414828;7069.646517674096;7050.330218629222;7085.929140708527;7104.040954471184;7127.786166415016;7134;7214.2785721427435;7239.420846332281;7274.272572742683;7275.745348912763;7322.670479828421;7028.156621205488;7057.788266352018;7066.540674856324;7099.432045436456;7103.609301488261;6894.413974812197;7023.192332881579;7032.789016329519;7033.191183013814;7057.929420705727;7229.891551626624;7253.202147763636;7271.818204544928;7302.3792977597495;7308.123025237006;4535.954640453553;4568.543145685405;4576.8626941191815;4577.33628623841;4578.313253012075;6900.309969003059;6966.233714291525;6983.476239282176;7056.50604457683;7073.717051318095;6780.93219067803;6814.420774234048;6826.658667066527;6854.862902742017;6862.794116176523;6812.65936703153;6829.487788415988;6850.177978642396;6862.622555759481;6878.828029299108;6531.738730450728;6682.465402767864;6744.460443164633;6753.088333074972;6762.695678694372;6869.278725733726;6884.51808373409;6887.414569761627;6893.7931862044215;6905.309469053054;6524.869502610016;6709.899351509634;6709.899351509829;6719.832004199934;6722.495812813961 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: |5;yui.html;743.695085015144;761.3756719490018;823.6726693132434;827.9544625045623;874.9037605863356;13063.73872522549;13309;13396.866031339683;13457.663558411045;13498.190108593491;13171.538996135137;13201.603951881434;14566.417343306282;14610.415583376653;14786.704265914675;806.0488623424357;809.4940662086196;830.3315830756238;830.5224495914842;831.580052073702;810.1048341582555;813.9796505087361;814.5112932240668;821.1583127294512;824.9216324449168;851.1105894340429;853.4751128056263;857.7040920882317;859.0550394565959;862.168007872403;854.3378881366929;855.8373908957309;856.8586183279751;857.6140736668493;865.7619154732467;637.4549759955217;637.5632691606219;638.3648269971362;638.5242993969476;639.0924886660953;13840.100393474391;14048.086874353134;14222.573322800332;14305.64235894111;14359.353829077716;11117.11063114957;11248.15638827083;12288.877111228952;12583;12622.368881555885;9786.951065244628;9878.654247101316;10212.540435680337;10430.009149130821;10730.892691073068;3512.771669841452;3583.122135076897;3694.741368104234;3707.443883417482;3721.1999420124534;48938.7553062236;49511.50488495141;49580;49585.752071239775;49736;9933.552990115462;10317.48412579383;10411.5835366586;10662;10687.572497100029;17741.290348386177;17784.911075444797;17805.46583602494;17816.37642682526;17822.91088544549;5644.802431914945;5780.768769249183;7321.633918304064;7401.926720625479;7474.40204783616;4137.40007698888;4168.478940132483;4191.937120943188;4219.45147130871;4300.247456695066;3116.3455674308198;3142.7328677062246;3142.7328677062706;3155.763317751178;3162.509810979302;559.4908633143901;564.7627996241588;568.2697733412544;570.9115087161497;583.3990989281048;649.3149727037926;659.7196191618543;663.7709990053423;680.5542369747823;684.1516519515765;7880.763577092696;7962.880556791652;8044.557549334842;8128.146544612849;8221.17788221173;601.9307779605401;656.9474442044625;662.0863208771864;664.846491337529;715.9355657990806;3596.8021758803516;3854.6338097880657;3856.749311294757;3886.5724770275247;4140.15126898985;53729.1940620891;58364.41635583675;58509;58515.5371115717;58663.70668146632;50139.24791128136;52235.21647175295;52889.47110528922;53252.201216981775;53654;48092.759536202095;49030.26454603184;49468.257976130386;50017.24974125391;50067.24899126516;8157.490864190144;8238.50568965864;8289.668413263402;8298.460600060977;8411.45325553837;32451.555520022233;32589.18527036843;33946.66053339435;35216.82391588025;35450.29099417946;44432;44834.758262086114;44836.551634483236;46246.7687661573;46683.76658116688;10883.129349652167;10993.725156871142;11055.834162487408;11204.831927521256;11496.655100347003;11465.94267028693;11555.826662600235;11595.188336816342;11595.8260626089;12064.698382540506;10565.349362281158;10570.682879513626;10601.681949541526;10826.891731082904;10863.185261105607;9951.253655975686;10010.749731256778;10087.445190514592;10240.692779216635;10488.423136727248;10376.533056012346;10559.472026398496;10712.250142490262;10733.248672593156;11146.49840757184;9076.617122679578;9133.269338452776;9261.907380926104;9515.191208747334;9611.375260608038;8867.645294188418;9281.48951807657;9809.4604796734;9874.607367753424;9991.550380232762 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,309 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: __startTimestamp1448107581371__endTimestamp 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,381 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5624.15 Median:5851.82 stddev:653.75 (11.2%) stddev-sans-first:628.81 04:06:21 INFO - 2015-11-21 04:06:21,382 DEBUG : BROWSER_OUTPUT: Values: 3256.2 3348.3 3365.5 3380.8 3392.7 5470.5 5629.9 5692.4 5707.1 5713.4 5564.7 5588.5 5612.0 5644.4 5674.3 5047.8 5076.5 5208.6 5249.9 5314.2 5242.5 5251.9 5273.2 5300.6 5332.8 5023.6 5058.4 5119.9 5156.9 5158.9 5094.7 5114.6 5121.8 5121.9 5126.7 5087.3 5101.5 5110.2 5119.4 5125.1 5211.9 5283.6 5352.1 5390.5 5412.9 6134.4 6165.5 6177.0 6178.0 6210.2 5836.2 6189.3 6216.5 6220.2 6244.4 5642.7 5684.4 5717.6 5740.1 5791.2 6183.8 6247.9 6260.4 6261.4 6281.6 5787.8 5851.8 6044.3 6051.0 6056.3 5862.2 5974.0 5985.4 5988.0 5991.8 5948.4 6082.0 6091.5 6107.8 6111.7 5862.5 5944.9 5993.0 6065.8 6091.2 4503.9 4507.5 4525.1 4551.3 4568.0 4027.4 4035.7 4038.9 4079.7 4085.6 5564.9 5676.8 5682.9 5730.8 5873.1 6021.9 6053.5 6070.4 6101.6 6107.1 5723.7 5734.9 5735.1 5744.2 5773.2 5078.6 5133.3 5159.1 5216.0 5256.4 6204.0 6274.2 6317.8 6331.6 6340.3 6034.0 6172.3 6198.4 6207.6 6212.8 6107.2 6111.5 6194.3 6195.2 6222.9 5934.6 6003.3 6038.0 6046.5 6074.2 5938.3 6008.0 6133.0 6161.0 6166.2 5897.4 6003.1 6003.4 6003.9 6046.0 5979.9 6041.1 6041.8 6042.8 6058.8 5692.1 5764.6 5805.8 5949.6 5951.1 5840.6 5881.6 5887.8 5953.4 6003.6 5641.8 5815.1 5885.4 5931.6 5969.5 04:06:21 INFO - 2015-11-21 04:06:21,382 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,382 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48526.26 Median:37596.25 stddev:83703.49 (222.6%) stddev-sans-first:83860.87 04:06:21 INFO - 2015-11-21 04:06:21,383 DEBUG : BROWSER_OUTPUT: Values: 573.6 580.3 584.6 588.8 623.3 37107.3 37999.4 38104.6 38159.6 38221.8 40175.6 40655.0 40812.4 40853.8 40894.0 8567.4 8702.7 8715.4 8731.4 8754.2 8560.5 8686.8 8693.8 8697.1 8698.3 8597.8 8737.0 8738.2 8739.0 8742.2 8519.9 8662.0 8662.5 8664.0 8690.0 8573.1 8705.7 8710.2 8718.2 8731.8 36954.0 37056.8 37118.8 37220.4 37262.4 3132.4 3159.6 3229.7 3308.6 3838.0 29137.4 29183.6 29386.3 29390.7 29897.9 3736.8 3881.8 3885.5 3937.9 4076.7 256925.0 259240.0 259380.0 259748.0 260065.0 51360.7 53092.7 59318.0 59434.7 59653.4 70275.0 71248.6 71273.0 71396.0 71515.0 14969.8 15160.5 15260.3 15307.7 15339.2 10852.3 10949.1 10971.8 11036.3 11062.8 20214.1 20651.6 20679.7 20744.9 20751.2 8137.6 8399.6 8444.0 8454.9 8548.9 895.0 903.5 984.7 1003.3 1015.7 27763.2 31445.7 32434.0 32785.5 32980.8 1036.1 1045.4 1099.5 1131.3 1145.1 13771.2 14319.9 14562.5 14596.6 14796.7 387336.0 389814.0 394642.0 394926.0 395146.0 50000.2 50271.7 50289.2 50350.2 50370.2 1308.9 1313.8 1324.0 1331.3 1333.4 26360.3 26955.6 27429.7 27625.0 27734.6 43067.0 43107.6 43127.4 43354.6 43692.0 288216.0 289154.0 289272.0 289455.0 290041.0 31231.0 44739.6 44820.8 45569.8 45735.8 42862.6 43092.4 43435.3 43478.0 43727.3 36374.0 36751.3 37333.8 37427.3 41277.0 36630.1 37104.0 37159.0 37765.2 37943.6 35503.5 35696.6 35835.5 35998.0 36244.3 32140.2 32406.8 33073.7 33251.0 33403.5 25986.5 27590.4 27713.9 27785.2 27986.2 04:06:21 INFO - 2015-11-21 04:06:21,383 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,383 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24052.96 Median:2028.53 stddev:63094.43 (3110.4%) stddev-sans-first:63246.33 04:06:21 INFO - 2015-11-21 04:06:21,384 DEBUG : BROWSER_OUTPUT: Values: 351.2 355.1 355.4 360.1 360.9 16468.7 16970.6 17635.2 17816.2 18349.6 15996.0 16088.2 16156.9 16175.6 16530.0 4712.9 4841.7 4846.8 4858.1 4871.4 4789.3 4806.2 4839.9 4840.6 4929.2 3687.3 3817.1 5258.5 5519.4 5555.8 5297.9 5327.5 5354.3 5374.7 5375.1 4711.9 5107.9 5151.6 5435.5 5458.3 11853.8 11912.2 12629.2 20686.2 20862.2 1985.2 2069.8 2102.8 2130.6 2263.7 9297.0 9323.7 12713.1 13502.5 14144.6 1895.1 1934.6 1980.1 2019.2 2031.8 58807.0 62737.0 63640.7 63685.0 64059.0 15587.8 15841.7 15847.4 15904.6 15978.3 24094.3 24345.9 24604.6 24698.3 24751.3 8656.5 8929.9 8951.3 8990.8 9003.5 7606.5 7614.1 7616.7 7632.0 7655.3 7959.3 7968.2 7980.4 8101.3 8193.1 3114.6 3133.9 3175.0 3199.3 3247.2 369.7 370.8 927.1 982.5 983.6 10172.1 10294.2 10497.0 10524.6 10589.2 391.1 395.6 396.5 397.3 397.6 5545.6 5587.1 5634.2 5665.1 5677.3 377575.0 384524.0 387568.0 388711.0 392181.0 42620.8 42629.4 47071.3 70527.0 71795.0 1109.3 1126.5 1131.1 1142.7 1145.2 15881.5 15932.2 15957.2 16035.0 16218.8 34507.0 34695.5 36840.3 37005.4 37558.8 43020.0 46262.5 46460.5 46617.0 46728.5 8836.9 9241.7 9795.8 9918.5 9943.8 10632.5 10706.6 10953.8 18018.7 18169.2 14570.6 14864.2 15010.4 15089.0 15238.5 13310.5 13416.9 13515.5 13670.9 14476.4 13592.3 13969.1 14035.4 14842.4 14902.1 8639.2 8753.5 8830.2 8896.7 9104.2 9497.8 11002.7 13956.9 14061.1 14304.9 04:06:21 INFO - 2015-11-21 04:06:21,384 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,384 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5444.30 Median:6246.56 stddev:2791.59 (44.7%) stddev-sans-first:2783.79 04:06:21 INFO - 2015-11-21 04:06:21,385 DEBUG : BROWSER_OUTPUT: Values: 1513.7 1639.2 1711.8 1735.9 1763.2 6729.3 6845.2 7759.5 7904.8 7936.7 7354.6 7493.3 7533.5 7535.2 7555.5 2235.2 2276.5 2280.2 2281.9 2286.6 2261.8 2279.6 2292.7 2299.3 2301.7 2283.0 2285.2 2300.7 2300.9 2331.0 2231.7 2240.5 2244.7 2263.2 2285.0 2279.3 2326.2 2328.8 2343.4 2346.4 7783.1 7820.3 7849.0 7872.9 7908.2 4742.4 4803.7 4810.6 4832.3 4854.1 3026.3 3065.0 3093.6 3137.2 3143.0 986.5 991.6 1766.5 1842.9 1924.7 10619.2 10673.6 10708.1 10772.5 10786.5 4176.1 4187.4 4217.3 4225.5 4315.9 8811.0 8845.1 8852.0 8884.0 8917.3 4733.9 4776.1 4783.5 4788.3 4794.7 4217.3 4317.7 4334.7 4344.6 4374.4 3647.5 3655.2 3669.2 3689.1 3689.5 1156.3 1159.1 1160.6 1164.9 1171.9 1335.1 1352.1 1359.2 1372.5 1381.4 7341.7 7419.9 7445.5 7574.0 7574.9 483.7 493.2 493.7 502.5 504.2 5599.2 5668.0 5674.3 5678.4 5713.2 9130.0 9552.4 9604.2 9654.8 9686.7 9085.0 9085.8 9157.6 9221.7 9289.0 7049.7 7121.5 7192.0 7210.5 7212.8 6984.0 6984.5 7007.6 7065.0 7065.0 8051.8 8110.5 8328.8 8342.0 8362.6 8416.8 8440.3 8492.3 8540.6 8547.8 6877.8 6879.5 6946.9 7035.1 7074.1 7607.5 7629.0 7629.0 7643.9 7669.8 6950.7 7091.6 7146.3 7155.0 7365.0 7025.3 7034.0 7068.4 7158.8 7261.0 6847.7 6859.5 6929.0 6961.7 6967.7 6337.3 6396.8 6428.6 6437.5 6464.9 6143.0 6160.4 6244.3 6248.9 6267.0 04:06:21 INFO - 2015-11-21 04:06:21,385 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,385 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5574.50 Median:6329.71 stddev:1748.57 (27.6%) stddev-sans-first:1708.03 04:06:21 INFO - 2015-11-21 04:06:21,385 DEBUG : BROWSER_OUTPUT: Values: 297.6 304.7 305.0 306.2 306.4 6220.5 6254.1 6293.4 6373.7 6378.3 6097.8 6150.4 6242.8 6255.6 6264.2 4636.9 4674.9 4718.1 4726.9 4729.9 4712.7 4713.7 4740.6 4767.4 4782.8 4621.3 4626.4 4705.4 4725.8 4734.5 4712.7 4713.6 4714.6 4718.1 4721.5 4094.2 4095.6 4110.5 4113.1 4118.3 5980.6 5989.1 5994.0 6011.3 6027.4 5241.4 5402.5 5414.4 5414.7 5500.7 6662.9 6679.4 6687.8 6689.3 6689.5 4383.0 4384.5 4390.9 4395.8 4408.3 6783.8 6962.6 7018.6 7021.4 7037.1 4516.6 4535.3 4545.4 4545.5 4552.2 4546.7 4568.0 4577.8 4586.8 4619.6 5374.1 5383.0 5386.7 5401.0 5402.8 6623.3 6714.4 6765.4 6774.9 6777.5 1588.2 1590.0 1626.1 1627.8 1630.5 1037.3 1042.1 1060.2 1063.2 1068.5 6308.3 6337.5 6365.7 6380.6 6388.5 6862.8 6885.0 6885.9 6890.5 6899.6 6167.8 6321.9 6342.4 6377.0 6389.1 3728.1 3749.7 3752.7 3753.8 3764.1 7044.9 7053.3 7061.3 7063.8 7069.6 7050.3 7085.9 7104.0 7127.8 7134.0 7214.3 7239.4 7274.3 7275.7 7322.7 7028.2 7057.8 7066.5 7099.4 7103.6 6894.4 7023.2 7032.8 7033.2 7057.9 7229.9 7253.2 7271.8 7302.4 7308.1 4536.0 4568.5 4576.9 4577.3 4578.3 6900.3 6966.2 6983.5 7056.5 7073.7 6780.9 6814.4 6826.7 6854.9 6862.8 6812.7 6829.5 6850.2 6862.6 6878.8 6531.7 6682.5 6744.5 6753.1 6762.7 6869.3 6884.5 6887.4 6893.8 6905.3 6524.9 6709.9 6709.9 6719.8 6722.5 04:06:21 INFO - 2015-11-21 04:06:21,385 DEBUG : BROWSER_OUTPUT: 04:06:21 INFO - 2015-11-21 04:06:21,385 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13830.45 Median:49545.75 stddev:16323.31 (32.9%) stddev-sans-first:16339.51 04:06:21 INFO - 2015-11-21 04:06:21,386 DEBUG : BROWSER_OUTPUT: Values: 743.7 761.4 823.7 828.0 874.9 13063.7 13309.0 13396.9 13457.7 13498.2 13171.5 13201.6 14566.4 14610.4 14786.7 806.0 809.5 830.3 830.5 831.6 810.1 814.0 814.5 821.2 824.9 851.1 853.5 857.7 859.1 862.2 854.3 855.8 856.9 857.6 865.8 637.5 637.6 638.4 638.5 639.1 13840.1 14048.1 14222.6 14305.6 14359.4 11117.1 11248.2 12288.9 12583.0 12622.4 9787.0 9878.7 10212.5 10430.0 10730.9 3512.8 3583.1 3694.7 3707.4 3721.2 48938.8 49511.5 49580.0 49585.8 49736.0 9933.6 10317.5 10411.6 10662.0 10687.6 17741.3 17784.9 17805.5 17816.4 17822.9 5644.8 5780.8 7321.6 7401.9 7474.4 4137.4 4168.5 4191.9 4219.5 4300.2 3116.3 3142.7 3142.7 3155.8 3162.5 559.5 564.8 568.3 570.9 583.4 649.3 659.7 663.8 680.6 684.2 7880.8 7962.9 8044.6 8128.1 8221.2 601.9 656.9 662.1 664.8 715.9 3596.8 3854.6 3856.7 3886.6 4140.2 53729.2 58364.4 58509.0 58515.5 58663.7 50139.2 52235.2 52889.5 53252.2 53654.0 48092.8 49030.3 49468.3 50017.2 50067.2 8157.5 8238.5 8289.7 8298.5 8411.5 32451.6 32589.2 33946.7 35216.8 35450.3 44432.0 44834.8 44836.6 46246.8 46683.8 10883.1 10993.7 11055.8 11204.8 11496.7 11465.9 11555.8 11595.2 11595.8 12064.7 10565.3 10570.7 10601.7 10826.9 10863.2 9951.3 10010.7 10087.4 10240.7 10488.4 10376.5 10559.5 10712.3 10733.2 11146.5 9076.6 9133.3 9261.9 9515.2 9611.4 8867.6 9281.5 9809.5 9874.6 9991.6 04:06:21 INFO - 2015-11-21 04:06:21,386 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:06:21 INFO - 2015-11-21 04:06:21,386 DEBUG : BROWSER_OUTPUT: 04:06:22 INFO - 2015-11-21 04:06:22,044 INFO : Browser exited with error code: 0 04:06:22 INFO - 2015-11-21 04:06:22,068 INFO : Completed test dromaeo_css (00:18:32) 04:06:22 INFO - 2015-11-21 04:06:22,068 INFO : Starting test kraken 04:06:22 INFO - 2015-11-21 04:06:22,068 DEBUG : operating with platform_type : linux_ 04:06:22 INFO - 2015-11-21 04:06:22,068 INFO : Initialising browser for kraken test... 04:06:22 INFO - 2015-11-21 04:06:22,075 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnFw7Ky/profile http://localhost:52360/getInfo.html 04:06:26 INFO - 2015-11-21 04:06:26,616 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:06:26 INFO - 2015-11-21 04:06:26,616 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:06:26 INFO - 2015-11-21 04:06:26,616 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:06:26 INFO - 2015-11-21 04:06:26,616 DEBUG : BROWSER_OUTPUT: __metrics 04:06:27 INFO - 2015-11-21 04:06:27,652 INFO : Browser initialized. 04:06:27 INFO - 2015-11-21 04:06:27,653 INFO : Running cycle 1/1 for kraken test... 04:06:27 INFO - 2015-11-21 04:06:27,653 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnFw7Ky/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:06:39 INFO - 2015-11-21 04:06:39,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 141946880 04:06:39 INFO - 2015-11-21 04:06:39,360 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-11-21 04:06:41,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52360/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:42 INFO - 2015-11-21 04:06:42,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 141946880 04:06:42 INFO - 2015-11-21 04:06:42,415 DEBUG : BROWSER_OUTPUT: 04:06:45 INFO - 2015-11-21 04:06:45,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52360/tests/kraken/driver.html?testName=audio-dft) 04:06:45 INFO - 2015-11-21 04:06:45,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 142413824 04:06:45 INFO - 2015-11-21 04:06:45,656 DEBUG : BROWSER_OUTPUT: 04:06:48 INFO - 2015-11-21 04:06:48,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52360/tests/kraken/driver.html?testName=audio-fft) 04:06:49 INFO - 2015-11-21 04:06:49,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 136470528 04:06:49 INFO - 2015-11-21 04:06:49,065 DEBUG : BROWSER_OUTPUT: 04:06:51 INFO - 2015-11-21 04:06:51,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52360/tests/kraken/driver.html?testName=audio-oscillator) 04:06:51 INFO - 2015-11-21 04:06:51,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 137338880 04:06:51 INFO - 2015-11-21 04:06:51,625 DEBUG : BROWSER_OUTPUT: 04:06:53 INFO - 2015-11-21 04:06:53,770 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52360/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:54 INFO - 2015-11-21 04:06:54,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 137621504 04:06:54 INFO - 2015-11-21 04:06:54,352 DEBUG : BROWSER_OUTPUT: 04:06:57 INFO - 2015-11-21 04:06:57,351 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52360/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:57 INFO - 2015-11-21 04:06:57,937 DEBUG : BROWSER_OUTPUT: RSS: Main: 137187328 04:06:57 INFO - 2015-11-21 04:06:57,937 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2015-11-21 04:07:01,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52360/tests/kraken/driver.html?testName=imaging-desaturate) 04:07:01 INFO - 2015-11-21 04:07:01,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 138625024 04:07:01 INFO - 2015-11-21 04:07:01,907 DEBUG : BROWSER_OUTPUT: 04:07:04 INFO - 2015-11-21 04:07:04,772 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52360/tests/kraken/driver.html?testName=json-parse-financial) 04:07:05 INFO - 2015-11-21 04:07:05,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 139280384 04:07:05 INFO - 2015-11-21 04:07:05,361 DEBUG : BROWSER_OUTPUT: 04:07:06 INFO - 2015-11-21 04:07:06,949 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52360/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:07:07 INFO - 2015-11-21 04:07:07,516 DEBUG : BROWSER_OUTPUT: RSS: Main: 139419648 04:07:07 INFO - 2015-11-21 04:07:07,517 DEBUG : BROWSER_OUTPUT: 04:07:09 INFO - 2015-11-21 04:07:09,333 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:07:09 INFO - 2015-11-21 04:07:09,940 DEBUG : BROWSER_OUTPUT: RSS: Main: 139616256 04:07:09 INFO - 2015-11-21 04:07:09,941 DEBUG : BROWSER_OUTPUT: 04:07:12 INFO - 2015-11-21 04:07:12,033 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:07:12 INFO - 2015-11-21 04:07:12,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 139497472 04:07:12 INFO - 2015-11-21 04:07:12,620 DEBUG : BROWSER_OUTPUT: 04:07:15 INFO - 2015-11-21 04:07:15,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:07:15 INFO - 2015-11-21 04:07:15,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 139685888 04:07:15 INFO - 2015-11-21 04:07:15,642 DEBUG : BROWSER_OUTPUT: 04:07:18 INFO - 2015-11-21 04:07:18,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:07:18 INFO - 2015-11-21 04:07:18,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 138797056 04:07:18 INFO - 2015-11-21 04:07:18,845 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,526 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52360/tests/kraken/driver.html?testName=ai-astar) 04:07:20 INFO - 2015-11-21 04:07:20,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 139010048 04:07:20 INFO - 2015-11-21 04:07:20,785 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,785 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:07:20 INFO - 2015-11-21 04:07:20,785 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:07:20 INFO - 2015-11-21 04:07:20,785 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:07:20 INFO - 2015-11-21 04:07:20,785 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:07:20 INFO - 2015-11-21 04:07:20,786 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;104;109;109;113;124;110;112;109;111 04:07:20 INFO - 2015-11-21 04:07:20,786 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;130;155;165;125;152;158;130;166;126 04:07:20 INFO - 2015-11-21 04:07:20,786 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;162;162;164;160;160;160;166;166 04:07:20 INFO - 2015-11-21 04:07:20,786 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;74;72;73;73;151;73;72;74;72 04:07:20 INFO - 2015-11-21 04:07:20,787 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;91;129;91;90;90;130;89;90;134 04:07:20 INFO - 2015-11-21 04:07:20,787 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;111;109;109;110;111;109;110 04:07:20 INFO - 2015-11-21 04:07:20,787 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;150;146;146;148;148;146;147;146;148;146 04:07:20 INFO - 2015-11-21 04:07:20,787 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;96;96;97;101;96;97;96;96;96 04:07:20 INFO - 2015-11-21 04:07:20,787 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;62;63;65;63;63;61;63;62;67 04:07:20 INFO - 2015-11-21 04:07:20,788 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;69;68;70;70;69;72;70;69 04:07:20 INFO - 2015-11-21 04:07:20,788 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;89;90;85;92;84;83;86;85;84 04:07:20 INFO - 2015-11-21 04:07:20,788 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;121;117;123;117;127;125;115;121;123 04:07:20 INFO - 2015-11-21 04:07:20,788 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;161;151;161;151;145;151;150;157;147 04:07:20 INFO - 2015-11-21 04:07:20,788 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;70;69;69;71;67;66;66;68;70 04:07:20 INFO - 2015-11-21 04:07:20,788 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,707 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: __startTimestamp1448107640779__endTimestamp 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:07:20 INFO - 2015-11-21 04:07:20,789 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:07:20 INFO - 2015-11-21 04:07:20,790 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,790 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:5.12 (4.6%) stddev-sans-first:5.43 04:07:20 INFO - 2015-11-21 04:07:20,790 DEBUG : BROWSER_OUTPUT: Values: 111.0 104.0 109.0 109.0 113.0 124.0 110.0 112.0 109.0 111.0 04:07:20 INFO - 2015-11-21 04:07:20,790 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,791 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.60 Median:153.50 stddev:16.27 (10.6%) stddev-sans-first:17.21 04:07:20 INFO - 2015-11-21 04:07:20,791 DEBUG : BROWSER_OUTPUT: Values: 149.0 130.0 155.0 165.0 125.0 152.0 158.0 130.0 166.0 126.0 04:07:20 INFO - 2015-11-21 04:07:20,791 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,792 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.40 Median:162.50 stddev:2.32 (1.4%) stddev-sans-first:2.45 04:07:20 INFO - 2015-11-21 04:07:20,792 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 162.0 162.0 164.0 160.0 160.0 160.0 166.0 166.0 04:07:20 INFO - 2015-11-21 04:07:20,792 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,792 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.60 Median:73.00 stddev:24.75 (33.9%) stddev-sans-first:26.05 04:07:20 INFO - 2015-11-21 04:07:20,793 DEBUG : BROWSER_OUTPUT: Values: 72.0 74.0 72.0 73.0 73.0 151.0 73.0 72.0 74.0 72.0 04:07:20 INFO - 2015-11-21 04:07:20,793 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,793 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.40 Median:90.00 stddev:19.78 (22.0%) stddev-sans-first:20.47 04:07:20 INFO - 2015-11-21 04:07:20,793 DEBUG : BROWSER_OUTPUT: Values: 90.0 91.0 129.0 91.0 90.0 90.0 130.0 89.0 90.0 134.0 04:07:20 INFO - 2015-11-21 04:07:20,794 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,795 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 04:07:20 INFO - 2015-11-21 04:07:20,795 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 111.0 109.0 109.0 110.0 111.0 109.0 110.0 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.50 stddev:1.37 (0.9%) stddev-sans-first:0.97 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: Values: 150.0 146.0 146.0 148.0 148.0 146.0 147.0 146.0 148.0 146.0 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.80 Median:96.00 stddev:1.55 (1.6%) stddev-sans-first:1.64 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 96.0 97.0 101.0 96.0 97.0 96.0 96.0 96.0 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:1.73 (2.7%) stddev-sans-first:1.79 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: Values: 62.0 62.0 63.0 65.0 63.0 63.0 61.0 63.0 62.0 67.0 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,796 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:1.07 (1.5%) stddev-sans-first:1.12 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 68.0 70.0 70.0 69.0 72.0 70.0 69.0 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:85.50 stddev:3.14 (3.7%) stddev-sans-first:3.13 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: Values: 83.0 89.0 90.0 85.0 92.0 84.0 83.0 86.0 85.0 84.0 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.90 Median:122.00 stddev:3.78 (3.1%) stddev-sans-first:4.00 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: Values: 120.0 121.0 117.0 123.0 117.0 127.0 125.0 115.0 121.0 123.0 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:151.00 stddev:6.55 (4.3%) stddev-sans-first:5.74 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: Values: 141.0 161.0 151.0 161.0 151.0 145.0 151.0 150.0 157.0 147.0 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.77 (2.6%) stddev-sans-first:1.81 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: Values: 67.0 70.0 69.0 69.0 71.0 67.0 66.0 66.0 68.0 70.0 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:07:20 INFO - 2015-11-21 04:07:20,797 DEBUG : BROWSER_OUTPUT: 04:07:21 INFO - 2015-11-21 04:07:21,668 INFO : Browser exited with error code: 0 04:07:21 INFO - 2015-11-21 04:07:21,724 INFO : Completed test kraken (00:00:59) 04:07:21 INFO - 2015-11-21 04:07:21,724 INFO : Starting test v8_7 04:07:21 INFO - 2015-11-21 04:07:21,724 DEBUG : operating with platform_type : linux_ 04:07:21 INFO - 2015-11-21 04:07:21,724 INFO : Initialising browser for v8_7 test... 04:07:21 INFO - 2015-11-21 04:07:21,730 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQolnUf/profile http://localhost:52360/getInfo.html 04:07:26 INFO - 2015-11-21 04:07:26,316 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:07:26 INFO - 2015-11-21 04:07:26,316 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:07:26 INFO - 2015-11-21 04:07:26,316 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:07:26 INFO - 2015-11-21 04:07:26,317 DEBUG : BROWSER_OUTPUT: __metrics 04:07:27 INFO - 2015-11-21 04:07:27,384 INFO : Browser initialized. 04:07:27 INFO - 2015-11-21 04:07:27,385 INFO : Running cycle 1/1 for v8_7 test... 04:07:27 INFO - 2015-11-21 04:07:27,385 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQolnUf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:07:39 INFO - 2015-11-21 04:07:39,123 DEBUG : BROWSER_OUTPUT: RSS: Main: 142028800 04:07:39 INFO - 2015-11-21 04:07:39,123 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,498 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52360/tests/v8_7/run.html (next: http://localhost:52360/tests/v8_7/run.html) 04:08:00 INFO - 2015-11-21 04:08:00,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 137965568 04:08:00 INFO - 2015-11-21 04:08:00,745 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,746 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:08:00 INFO - 2015-11-21 04:08:00,746 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:08:00 INFO - 2015-11-21 04:08:00,746 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:08:00 INFO - 2015-11-21 04:08:00,746 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:08:00 INFO - 2015-11-21 04:08:00,746 DEBUG : BROWSER_OUTPUT: |0;Richards;171.3796058269066 04:08:00 INFO - 2015-11-21 04:08:00,747 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.42359767891682 04:08:00 INFO - 2015-11-21 04:08:00,747 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.58361391694723 04:08:00 INFO - 2015-11-21 04:08:00,747 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5060.606060606061 04:08:00 INFO - 2015-11-21 04:08:00,747 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1058.2010582010582 04:08:00 INFO - 2015-11-21 04:08:00,747 DEBUG : BROWSER_OUTPUT: |5;Earley;704.7216349541931 04:08:00 INFO - 2015-11-21 04:08:00,747 DEBUG : BROWSER_OUTPUT: |6;Boyer;12304.878048780487 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: |7;RegExp;25125 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: |8;Splay;618.4291898577612 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: __startTimestamp1448107680744__endTimestamp 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,748 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.38 Median:171.38 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,750 DEBUG : BROWSER_OUTPUT: Values: 171.4 04:08:00 INFO - 2015-11-21 04:08:00,750 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,750 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.42 Median:193.42 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,750 DEBUG : BROWSER_OUTPUT: Values: 193.4 04:08:00 INFO - 2015-11-21 04:08:00,750 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,750 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.58 Median:280.58 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: Values: 280.6 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5060.61 Median:5060.61 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: Values: 5060.6 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1058.20 Median:1058.20 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,751 DEBUG : BROWSER_OUTPUT: Values: 1058.2 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:704.72 Median:704.72 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: Values: 704.7 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12304.88 Median:12304.88 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: Values: 12304.9 04:08:00 INFO - 2015-11-21 04:08:00,752 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25125.00 Median:25125.00 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: Values: 25125.0 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:618.43 Median:618.43 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: Values: 618.4 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 04:08:00 INFO - 2015-11-21 04:08:00,753 DEBUG : BROWSER_OUTPUT: Values: 4835.7 04:08:00 INFO - 2015-11-21 04:08:00,754 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:08:00 INFO - 2015-11-21 04:08:00,754 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2015-11-21 04:08:01,604 INFO : Browser exited with error code: 0 04:08:01 INFO - 2015-11-21 04:08:01,616 INFO : Completed test v8_7 (00:00:39) 04:08:01 INFO - 2015-11-21 04:08:01,780 INFO : Completed test suite (00:20:11) 04:08:01 INFO - 2015-11-21 04:08:01,780 DEBUG : Working with test: dromaeo_css 04:08:01 INFO - 2015-11-21 04:08:01,780 DEBUG : Generating results file: dromaeo_css 04:08:01 INFO - 2015-11-21 04:08:01,782 DEBUG : Working with test: kraken 04:08:01 INFO - 2015-11-21 04:08:01,782 DEBUG : Generating results file: kraken 04:08:01 INFO - 2015-11-21 04:08:01,782 INFO : javascript benchmark 04:08:01 INFO - 2015-11-21 04:08:01,782 DEBUG : Working with test: v8_7 04:08:01 INFO - 2015-11-21 04:08:01,782 DEBUG : Generating results file: v8_7 04:08:01 INFO - 2015-11-21 04:08:01,783 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:08:01 INFO - 2015-11-21 04:08:01,849 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 04:08:01 INFO - 2015-11-21 04:08:01,849 DEBUG : process_Request line: dromaeo_css 7075.22 graph.html#tests=[[72,63,43]] 04:08:01 INFO - 2015-11-21 04:08:01,849 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:08:01 INFO - 2015-11-21 04:08:01,879 DEBUG : process_Request line: kraken 1515.30 graph.html#tests=[[232,63,43]] 04:08:01 INFO - 2015-11-21 04:08:01,879 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:08:01 INFO - 2015-11-21 04:08:01,911 DEBUG : process_Request line: v8_7 21297.32 graph.html#tests=[[230,63,43]] 04:08:01 INFO - 2015-11-21 04:08:01,912 INFO : javascript benchmark 04:08:01 INFO - 2015-11-21 04:08:01,916 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3256.2347848255654, 3348.2633820559486, 3365.478350855615, 3380.8309584520775, 3392.694657480826, 5470.507654311111, 5629.943700562998, 5692.402297758727, 5707.08686610143, 5713.371529131798, 5564.666120032801, 5588.497035266825, 5611.961787069395, 5644.350899646542, 5674.31908171019, 5047.84856454306, 5076.517730815566, 5208.635395522315, 5249.921251181236, 5314.229436731672, 5242.475752424746, 5251.921221181686, 5273.209018647195, 5300.575953923677, 5332.813351532697, 5023.648344615878, 5058.443571207183, 5119.923201151985, 5156.896862062757, 5158.92261616076, 5094.6688465249645, 5114.616403769732, 5121.82073627425, 5121.923171152435, 5126.726392299972, 5087.262346959707, 5101.540861322462, 5110.233464980245, 5119.411267704188, 5125.077486052509, 5211.921821172686, 5283.577313814885, 5352.116900711378, 5390.460953904618, 5412.864678383033, 6134.447899689005, 6165.537584681122, 6176.969115154396, 6178.0, 6210.1616281801835, 5836.212111364954, 6189.288231853305, 6216.533759968021, 6220.191375121205, 6244.4067813558095, 5642.661440313554, 5684.4315568443235, 5717.628354156966, 5740.052891272977, 5791.189233507313, 6183.81448556544, 6247.875042499125, 6260.373962603794, 6261.436470717657, 6281.623102613858, 5787.797427090031, 5851.824445266564, 6044.274687037584, 6050.969745151334, 6056.3338032816355, 5862.237909071793, 5974.044152935509, 5985.371535988746, 5988.04191329394, 5991.820245392645, 5948.375420580864, 6081.969590152021, 6091.482224011009, 6107.84730381744, 6111.694415279308, 5862.472377486047, 5944.910826337608, 5992.970035149797, 6065.848353791102, 6091.238595175603, 4503.932441013388, 4507.549245075466, 4525.072360166158, 4551.271796512542, 4567.977160114178, 4027.4416864163654, 4035.7174997749876, 4038.9394159087637, 4079.653229475528, 4085.611866872642, 5564.86087847799, 5676.801311954062, 5682.857928551819, 5730.8280751576685, 5873.060310350324, 6021.879562408727, 6053.545984051214, 6070.362611925772, 6101.60339577926, 6107.083937409424, 5723.685197314105, 5734.856628584319, 5735.139729040594, 5744.224529688522, 5773.24947756789, 5078.5937125029905, 5133.332666753298, 5159.071367153873, 5216.0, 5256.395514515803, 6204.0, 6274.1843560337775, 6317.842053948596, 6331.588446750947, 6340.270868850049, 6034.034554471264, 6172.321044685171, 6198.380161983766, 6207.565470417027, 6212.844678883064, 6107.219158344477, 6111.470193407107, 6194.318624951342, 6195.2255968004, 6222.906656400067, 5934.57061343777, 6003.279606447254, 6038.0, 6046.455818976313, 6074.210352654127, 5938.346781854079, 6008.0, 6133.049377346519, 6161.0, 6166.1983942087245, 5897.3807750185615, 6003.099535069775, 6003.42967418112, 6003.939960600338, 6046.0, 5979.850503737442, 6041.124037014521, 6041.78853740117, 6042.848928776816, 6058.787942421994, 5692.117721753135, 5764.567657425626, 5805.82582522525, 5949.553783466171, 5951.077582974646, 5840.591158619026, 5881.647101173943, 5887.8233652990475, 5953.404659534012, 6003.6397816131175, 5641.774329026957, 5815.127730840408, 5885.411458854252, 5931.6441013539325, 5969.492593129633], "unit": "ms", "name": "dojo.html", "value": 5579.766867135631}, {"lowerIsBetter": false, "replicates": [573.6328749600257, 580.3355158343696, 584.5995493087236, 588.8410129265097, 623.2676604989136, 37107.25785484292, 37999.43000854989, 38104.61895381045, 38159.618403815955, 38221.808890955544, 40175.59824401755, 40655.0, 40812.38781418281, 40853.795731021375, 40894.0, 8567.443116197443, 8702.738917832476, 8715.433496822687, 8731.388802783807, 8754.168354006359, 8560.529170895597, 8686.782830429258, 8693.7826554336, 8697.086805885387, 8698.304135669163, 8597.785055373603, 8736.956315218447, 8738.213560779528, 8739.038705742363, 8742.213200811926, 8519.872201916976, 8662.0, 8662.480251184948, 8664.046954834963, 8690.0, 8573.099824518398, 8705.651773929036, 8710.172533609297, 8718.21536061751, 8731.781705457352, 36954.0, 37056.814715926244, 37118.81440592807, 37220.44169337462, 37262.441063384074, 3132.3578666373564, 3159.6208454985317, 3229.660885607001, 3308.602967643874, 3837.963749787542, 29137.41725165485, 29183.56224656632, 29386.265343366373, 29390.70609293901, 29897.850510747525, 3736.75711078779, 3881.7670939743707, 3885.4560361549125, 3937.9015524611827, 4076.6942479313875, 256925.0, 259240.0, 259380.0, 259748.0, 260065.0, 51360.74319628378, 53092.734536327844, 59318.0, 59434.70282648646, 59653.40346596479, 70275.0, 71248.64375678089, 71273.0, 71396.0, 71515.0, 14969.775453368207, 15160.545183644288, 15260.31328590216, 15307.693846123015, 15339.233038348038, 10852.348859068481, 10949.069329106957, 10971.780564388668, 11036.282641628268, 11062.778744425228, 20214.079662432046, 20651.58696826055, 20679.689804652942, 20744.89627551883, 20751.169953202007, 8137.633806478722, 8399.580020998925, 8444.0, 8454.915450845536, 8548.914510854936, 894.9552522373986, 903.4624398482893, 984.6799790068256, 1003.2676146413013, 1015.720676813882, 27763.167104986882, 31445.685543144275, 32434.0, 32785.50821737676, 32980.83509582437, 1036.08306648615, 1045.38844775807, 1099.4997276239292, 1131.2567024964428, 1145.0953746540306, 13771.242581657905, 14319.85680143206, 14562.490312839, 14596.562103136923, 14796.70406591862, 387336.0, 389814.0, 394642.0, 394926.0, 395146.0, 50000.24999625009, 50271.74864125729, 50289.24566131511, 50350.24474632883, 50370.24444633334, 1308.8952883769275, 1313.835770528684, 1323.9669008274677, 1331.2811082015796, 1333.4066340478394, 26360.34099147537, 26955.595666064633, 27429.725702743515, 27625.0, 27734.583981239895, 43067.0, 43107.56892431161, 43127.35308970431, 43354.56645433505, 43692.0, 288216.0, 289154.0, 289272.0, 289455.0, 290041.0, 31231.0, 44739.55260447354, 44820.77589612162, 45569.77215114036, 45735.771321143184, 42862.57137428586, 43092.35361469643, 43435.34846977361, 43478.0, 43727.34408983804, 36374.0, 36751.26497470089, 37333.81333093317, 37427.251454971294, 41277.0, 36630.08424789402, 37104.0, 37159.0, 37765.24469510649, 37943.62056379401, 35503.46744798882, 35696.64303357037, 35835.462468063524, 35998.0, 36244.27511449809, 32140.19649508781, 32406.83796581002, 33073.669263307056, 33251.0, 33403.49894751532, 25986.480270394863, 27590.448191035666, 27713.86143069272, 27785.166445006682, 27986.16041518676], "unit": "ms", "name": "ext.html", "value": 18756.7672469521}, {"lowerIsBetter": false, "replicates": [351.2150343981202, 355.09273805427125, 355.3674459462158, 360.0799956112134, 360.85385418905344, 16468.670626587475, 16970.575735606602, 17635.206415711276, 17816.1982710778, 18349.633007339846, 15996.0, 16088.1955902205, 16156.919215403906, 16175.595610109753, 16530.0, 4712.929306060403, 4841.6852904561265, 4846.781894814724, 4858.125537403266, 4871.415430148387, 4789.257665061904, 4806.182948898678, 4839.927401088987, 4840.6369522285795, 4929.162042452774, 3687.3178461984485, 3817.0838998640406, 5258.50044245796, 5519.448055194469, 5555.833325000256, 5297.894042119136, 5327.46725327468, 5354.330708661418, 5374.677519348832, 5375.0593646111965, 4711.858644240679, 5107.923381149286, 5151.613628977804, 5435.537979271767, 5458.263134476834, 11853.762924741457, 11912.166148369617, 12629.179103358252, 20686.172553098007, 20862.16551337945, 1985.2356842615552, 2069.793020697933, 2102.8212601928853, 2130.5845360154776, 2263.7057182566327, 9296.95351523238, 9323.673671421468, 12713.110082294243, 13502.527411540548, 14144.646383840487, 1895.1187697720627, 1934.555052337956, 1980.1188471130354, 2019.1822311963679, 2031.7765045844944, 58807.0, 62737.0, 63640.68179659072, 63685.0, 64059.0, 15587.766183507256, 15841.683166336608, 15847.366105355888, 15904.602384940468, 15978.280977356048, 24094.349621541616, 24345.878270608533, 24604.630930536056, 24698.259052228103, 24751.25746227616, 8656.48061116335, 8929.910700892908, 8951.283897288202, 8990.820183596292, 9003.549822508849, 7606.467547271748, 7614.086309642878, 7616.695332186761, 7631.961840190875, 7655.311022008046, 7959.283664470109, 7968.203179681986, 7980.3615710743, 8101.270885620323, 8193.09875913649, 3114.5617495069437, 3133.85897634607, 3175.0, 3199.2641692410875, 3247.220667039893, 369.7411811731798, 370.77568071317006, 927.096081320707, 982.4989255479757, 983.5918093990914, 10172.135368493613, 10294.227932905062, 10497.0, 10524.631637892637, 10589.15286777056, 391.0516996284, 395.6281095769967, 396.5261512492544, 397.3272522679494, 397.6242450883945, 5545.584081193846, 5587.1339942309005, 5634.1830434586755, 5665.121906104478, 5677.2619559457835, 377575.0, 384524.0, 387568.0, 388711.0, 392181.0, 42620.78689606532, 42629.36055959163, 47071.29393059107, 70527.0, 71795.0, 1109.3288560420829, 1126.5324890170486, 1131.1177281720436, 1142.737170450801, 1145.2269717940358, 15881.52355429339, 15932.20338983023, 15957.202139893192, 16035.0, 16218.756718648994, 34507.0, 34695.479567805996, 36840.26319473649, 37005.44491832571, 37558.8122059388, 43020.0, 46262.53737462583, 46460.53539464697, 46617.0, 46728.532714673776, 8836.867446988428, 9241.676541321021, 9795.755106122404, 9918.454485003105, 9943.801123977624, 10632.52153653072, 10706.62526811589, 10953.780924381626, 18018.72971905396, 18169.182386792363, 14570.562883113524, 14864.182469963824, 15010.399584016517, 15089.0, 15238.542843714706, 13310.534131305358, 13416.932915335361, 13515.459381624625, 13670.931645342107, 14476.420943162577, 13592.252426116658, 13969.092009019381, 14035.368408421442, 14842.40630374773, 14902.105873647615, 8639.222469977733, 8753.47479151253, 8830.205281524693, 8896.733098007071, 9104.226140778108, 9497.762555936157, 11002.669919902415, 13956.930215348859, 14061.086029408465, 14304.856951430353], "unit": "ms", "name": "jquery.html", "value": 8609.651612317033}, {"lowerIsBetter": false, "replicates": [1513.7275290447728, 1639.1886016421872, 1711.7774689290395, 1735.9392421265259, 1763.2065570493273, 6729.32706729328, 6845.178578570579, 7759.495632783865, 7904.762857114289, 7936.682532698699, 7354.6322683865865, 7493.288137626931, 7533.472656914005, 7535.171131175566, 7555.546667199972, 2235.24001839375, 2276.4877902471976, 2280.1905323610126, 2281.942951426219, 2286.6112760830697, 2261.8416710830247, 2279.6466547685136, 2292.747797742247, 2299.3446867642724, 2301.7468078511433, 2283.041122728458, 2285.2344464604375, 2300.6779050932882, 2300.884955752214, 2330.9650355244685, 2231.7098777158944, 2240.5406891587186, 2244.7194100737406, 2263.20787724296, 2285.040283081093, 2279.3048120323297, 2326.220716060122, 2328.7904088631935, 2343.4492894169857, 2346.366481050124, 7783.066032076187, 7820.296173344369, 7848.9607551962445, 7872.921270787333, 7908.248716371935, 4742.407199100113, 4803.663743537953, 4810.5670489656095, 4832.347633069526, 4854.12625727369, 3026.3493348929865, 3065.049834551296, 3093.566900633913, 3137.247060705437, 3143.0413723814227, 986.4574484033825, 991.5934466868622, 1766.4612293250555, 1842.8617853660894, 1924.6728056230475, 10619.20355973305, 10673.573057077707, 10708.08981236588, 10772.4613769312, 10786.514606842711, 4176.1230141669985, 4187.371894215892, 4217.261979153697, 4225.4506914101585, 4315.913681726348, 8811.0, 8845.071267516947, 8851.955740221258, 8883.955580222058, 8917.331200159884, 4733.928991065137, 4776.068666609977, 4783.4692898272915, 4788.329633851264, 4794.7362895040405, 4217.325227963511, 4317.676174286941, 4334.696571239983, 4344.565543445693, 4374.387585737999, 3647.507586475792, 3655.214128962286, 3669.192777588926, 3689.114612493036, 3689.538807649044, 1156.3293289891842, 1159.1248607301602, 1160.5647882044234, 1164.8602167739757, 1171.8769529199483, 1335.105479328852, 1352.0738294268458, 1359.238826257299, 1372.4853180057478, 1381.4405165907824, 7341.669624866894, 7419.925800741924, 7445.478816482902, 7573.962130189314, 7574.886376704354, 483.7484508055791, 493.23302264978304, 493.6618416384788, 502.52762403340745, 504.1857400298565, 5599.160125981135, 5667.971660141673, 5674.2623458950075, 5678.375378708339, 5713.200151978726, 9129.954350228207, 9552.37909535878, 9604.231661467065, 9654.75863103428, 9686.70939871805, 9084.954575227082, 9085.77285567853, 9157.633694652273, 9221.723348299562, 9289.0, 7049.68276427562, 7121.501494895411, 7192.0, 7210.459215558854, 7212.783616491513, 6983.9650801745665, 6984.511084224056, 7007.649617519206, 7065.0, 7065.046218760607, 8051.758447246592, 8110.472819266729, 8328.791780205544, 8341.95829020851, 8362.623681934449, 8416.83166336682, 8440.324774018187, 8492.320614350961, 8540.572971351283, 8547.786305342415, 6877.793666190022, 6879.484038697218, 6946.895796563157, 7035.085438892911, 7074.115735533059, 7607.543547387175, 7628.961855190689, 7629.0, 7643.923560764321, 7669.808254793452, 6950.721971121301, 7091.645417729196, 7146.32109949554, 7155.0, 7365.042544469185, 7025.262347453444, 7034.0, 7068.399186069241, 7158.821029474305, 7260.96369518149, 6847.691853866688, 6859.451243900576, 6929.02993580889, 6961.686724097326, 6967.7212911482975, 6337.271213810471, 6396.840078998062, 6428.646424446513, 6437.485001199986, 6464.935350646433, 6143.0, 6160.414760597736, 6244.250689917239, 6248.875022499434, 6267.0], "unit": "ms", "name": "mootools.html", "value": 4458.959463880984}, {"lowerIsBetter": false, "replicates": [297.6175614335579, 304.69987062743206, 305.0269639848882, 306.19318096814885, 306.3811101574819, 6220.502359811216, 6254.093156492306, 6293.37066293371, 6373.681315934208, 6378.266499352576, 6097.756089756415, 6150.384961503847, 6242.750289988407, 6255.593386429879, 6264.21697287839, 4636.86089417318, 4674.883127921812, 4718.103560323545, 4726.905461890778, 4729.858104256869, 4712.670113092085, 4713.670043096985, 4740.5970492508, 4767.404074490689, 4782.83260085897, 4621.29414032953, 4626.398568186131, 4705.388299521058, 4725.834595789148, 4734.502877197896, 4712.670113092085, 4713.646476514258, 4714.6464015199, 4718.103560323528, 4721.527847215285, 4094.222097801438, 4095.631393174599, 4110.547839737656, 4113.136241389312, 4118.279301122321, 5980.611260268069, 5989.071693887455, 5993.970030149865, 6011.338752737195, 6027.427394397525, 5241.371035475768, 5402.486763757437, 5414.40441551429, 5414.729263536808, 5500.669959802424, 6662.900056499157, 6679.432248258904, 6687.76592819249, 6689.297623749533, 6689.531732778707, 4382.978085109586, 4384.517703052662, 4390.868273951786, 4395.780210989437, 4408.338749187616, 6783.796486105425, 6962.597903723207, 7018.613976231305, 7021.368076873106, 7037.085178926758, 4516.638668906479, 4535.297028960516, 4545.431821022372, 4545.454545454567, 4552.226121559276, 4546.659000574904, 4567.977160114178, 4577.8397756078375, 4586.816527338936, 4619.584237418581, 5374.086405311106, 5383.031054410244, 5386.730663466811, 5400.972995134999, 5402.810901618424, 6623.337666233435, 6714.429273511709, 6765.357291057342, 6774.864502709918, 6777.49168812341, 1588.1582761136617, 1590.0, 1626.1381467822073, 1627.8046634403947, 1630.4537979776671, 1037.3153718545723, 1042.0673497220098, 1060.1783617696346, 1063.2344711807486, 1068.4924660786219, 6308.337624549448, 6337.524685648503, 6365.745370185234, 6380.649064301508, 6388.456981156561, 6862.759803406857, 6884.965575172192, 6885.931140688629, 6890.483213758988, 6899.6205208713, 6167.784127555604, 6321.905171422341, 6342.39747224013, 6377.0, 6389.073584330339, 3728.12389088564, 3749.6812770914776, 3752.6997840173267, 3753.8123093845197, 3764.0589852536864, 7044.92955070453, 7053.259407762213, 7061.293870612898, 7063.823404414828, 7069.646517674096, 7050.330218629222, 7085.929140708527, 7104.040954471184, 7127.786166415016, 7134.0, 7214.2785721427435, 7239.420846332281, 7274.272572742683, 7275.745348912763, 7322.670479828421, 7028.156621205488, 7057.788266352018, 7066.540674856324, 7099.432045436456, 7103.609301488261, 6894.413974812197, 7023.192332881579, 7032.789016329519, 7033.191183013814, 7057.929420705727, 7229.891551626624, 7253.202147763636, 7271.818204544928, 7302.3792977597495, 7308.123025237006, 4535.954640453553, 4568.543145685405, 4576.8626941191815, 4577.33628623841, 4578.313253012075, 6900.309969003059, 6966.233714291525, 6983.476239282176, 7056.50604457683, 7073.717051318095, 6780.93219067803, 6814.420774234048, 6826.658667066527, 6854.862902742017, 6862.794116176523, 6812.65936703153, 6829.487788415988, 6850.177978642396, 6862.622555759481, 6878.828029299108, 6531.738730450728, 6682.465402767864, 6744.460443164633, 6753.088333074972, 6762.695678694372, 6869.278725733726, 6884.51808373409, 6887.414569761627, 6893.7931862044215, 6905.309469053054, 6524.869502610016, 6709.899351509634, 6709.899351509829, 6719.832004199934, 6722.495812813961], "unit": "ms", "name": "prototype.html", "value": 4982.174227009508}, {"lowerIsBetter": false, "replicates": [743.695085015144, 761.3756719490018, 823.6726693132434, 827.9544625045623, 874.9037605863356, 13063.73872522549, 13309.0, 13396.866031339683, 13457.663558411045, 13498.190108593491, 13171.538996135137, 13201.603951881434, 14566.417343306282, 14610.415583376653, 14786.704265914675, 806.0488623424357, 809.4940662086196, 830.3315830756238, 830.5224495914842, 831.580052073702, 810.1048341582555, 813.9796505087361, 814.5112932240668, 821.1583127294512, 824.9216324449168, 851.1105894340429, 853.4751128056263, 857.7040920882317, 859.0550394565959, 862.168007872403, 854.3378881366929, 855.8373908957309, 856.8586183279751, 857.6140736668493, 865.7619154732467, 637.4549759955217, 637.5632691606219, 638.3648269971362, 638.5242993969476, 639.0924886660953, 13840.100393474391, 14048.086874353134, 14222.573322800332, 14305.64235894111, 14359.353829077716, 11117.11063114957, 11248.15638827083, 12288.877111228952, 12583.0, 12622.368881555885, 9786.951065244628, 9878.654247101316, 10212.540435680337, 10430.009149130821, 10730.892691073068, 3512.771669841452, 3583.122135076897, 3694.741368104234, 3707.443883417482, 3721.1999420124534, 48938.7553062236, 49511.50488495141, 49580.0, 49585.752071239775, 49736.0, 9933.552990115462, 10317.48412579383, 10411.5835366586, 10662.0, 10687.572497100029, 17741.290348386177, 17784.911075444797, 17805.46583602494, 17816.37642682526, 17822.91088544549, 5644.802431914945, 5780.768769249183, 7321.633918304064, 7401.926720625479, 7474.40204783616, 4137.40007698888, 4168.478940132483, 4191.937120943188, 4219.45147130871, 4300.247456695066, 3116.3455674308198, 3142.7328677062246, 3142.7328677062706, 3155.763317751178, 3162.509810979302, 559.4908633143901, 564.7627996241588, 568.2697733412544, 570.9115087161497, 583.3990989281048, 649.3149727037926, 659.7196191618543, 663.7709990053423, 680.5542369747823, 684.1516519515765, 7880.763577092696, 7962.880556791652, 8044.557549334842, 8128.146544612849, 8221.17788221173, 601.9307779605401, 656.9474442044625, 662.0863208771864, 664.846491337529, 715.9355657990806, 3596.8021758803516, 3854.6338097880657, 3856.749311294757, 3886.5724770275247, 4140.15126898985, 53729.1940620891, 58364.41635583675, 58509.0, 58515.5371115717, 58663.70668146632, 50139.24791128136, 52235.21647175295, 52889.47110528922, 53252.201216981775, 53654.0, 48092.759536202095, 49030.26454603184, 49468.257976130386, 50017.24974125391, 50067.24899126516, 8157.490864190144, 8238.50568965864, 8289.668413263402, 8298.460600060977, 8411.45325553837, 32451.555520022233, 32589.18527036843, 33946.66053339435, 35216.82391588025, 35450.29099417946, 44432.0, 44834.758262086114, 44836.551634483236, 46246.7687661573, 46683.76658116688, 10883.129349652167, 10993.725156871142, 11055.834162487408, 11204.831927521256, 11496.655100347003, 11465.94267028693, 11555.826662600235, 11595.188336816342, 11595.8260626089, 12064.698382540506, 10565.349362281158, 10570.682879513626, 10601.681949541526, 10826.891731082904, 10863.185261105607, 9951.253655975686, 10010.749731256778, 10087.445190514592, 10240.692779216635, 10488.423136727248, 10376.533056012346, 10559.472026398496, 10712.250142490262, 10733.248672593156, 11146.49840757184, 9076.617122679578, 9133.269338452776, 9261.907380926104, 9515.191208747334, 9611.375260608038, 8867.645294188418, 9281.48951807657, 9809.4604796734, 9874.607367753424, 9991.550380232762], "unit": "ms", "name": "yui.html", "value": 6266.025398573692}], "name": "dromaeo_css", "value": 7075.22412515807}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 104.0, 109.0, 109.0, 113.0, 124.0, 110.0, 112.0, 109.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [149.0, 130.0, 155.0, 165.0, 125.0, 152.0, 158.0, 130.0, 166.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.6}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 162.0, 162.0, 164.0, 160.0, 160.0, 160.0, 166.0, 166.0], "unit": "ms", "name": "audio-dft", "value": 162.4}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 72.0, 73.0, 73.0, 151.0, 73.0, 72.0, 74.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.6}, {"lowerIsBetter": true, "replicates": [90.0, 91.0, 129.0, 91.0, 90.0, 90.0, 130.0, 89.0, 90.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 102.4}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 111.0, 109.0, 109.0, 110.0, 111.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [150.0, 146.0, 146.0, 148.0, 148.0, 146.0, 147.0, 146.0, 148.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.1}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 96.0, 97.0, 101.0, 96.0, 97.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.8}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 63.0, 65.0, 63.0, 63.0, 61.0, 63.0, 62.0, 67.0], "unit": "ms", "name": "json-parse-financial", "value": 63.1}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 68.0, 70.0, 70.0, 69.0, 72.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.6}, {"lowerIsBetter": true, "replicates": [83.0, 89.0, 90.0, 85.0, 92.0, 84.0, 83.0, 86.0, 85.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.1}, {"lowerIsBetter": true, "replicates": [120.0, 121.0, 117.0, 123.0, 117.0, 127.0, 125.0, 115.0, 121.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.9}, {"lowerIsBetter": true, "replicates": [141.0, 161.0, 151.0, 161.0, 151.0, 145.0, 151.0, 150.0, 157.0, 147.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 151.5}, {"lowerIsBetter": true, "replicates": [67.0, 70.0, 69.0, 69.0, 71.0, 67.0, 66.0, 66.0, 68.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.3}], "name": "kraken", "value": 1515.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.3796058269066], "unit": "score", "name": "Richards", "value": 205.98717000000005}, {"lowerIsBetter": false, "replicates": [193.42359767891682], "unit": "score", "name": "DeltaBlue", "value": 341.83006}, {"lowerIsBetter": false, "replicates": [280.58361391694723], "unit": "score", "name": "Encrypt", "value": 948.6690839999999}, {"lowerIsBetter": false, "replicates": [5060.606060606061], "unit": "score", "name": "Decrypt", "value": 52.59864071856286}, {"lowerIsBetter": false, "replicates": [1058.2010582010582], "unit": "score", "name": "RayTrace", "value": 699.2896049999997}, {"lowerIsBetter": false, "replicates": [704.7216349541931], "unit": "score", "name": "Earley", "value": 945.7109970000002}, {"lowerIsBetter": false, "replicates": [12304.878048780487], "unit": "score", "name": "Boyer", "value": 54.16250346878094}, {"lowerIsBetter": false, "replicates": [25125.0], "unit": "score", "name": "RegExp", "value": 36.25810945273629}, {"lowerIsBetter": false, "replicates": [618.4291898577612], "unit": "score", "name": "Splay", "value": 131.77094700000006}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21297.322825747724}]} 04:08:01 INFO - RETURN: dromaeo_css: 7075.22 04:08:01 INFO - RETURN: kraken: 1515.30 04:08:01 INFO - RETURN: v8_7: 21297.32 04:08:01 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "21297.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1515.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "7075.22"}}} 04:08:02 INFO - Return code: 0 04:08:02 INFO - # TBPL SUCCESS # 04:08:02 INFO - Running post-action listener: _resource_record_post_action 04:08:02 INFO - Running post-run listener: _resource_record_post_run 04:08:03 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 2519040; Write bytes: 289361920; Read time: 1124; Write time: 180640 04:08:03 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 6791168; Read time: 0; Write time: 1420 04:08:03 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 2519040; Write bytes: 282570752; Read time: 1124; Write time: 179220 04:08:03 INFO - Running post-run listener: _upload_blobber_files 04:08:03 INFO - Blob upload gear active. 04:08:03 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:08:03 INFO - Copying logs to upload dir... 04:08:03 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1286.483668 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2015-11-21 04:08:03.033216) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-21 04:08:03.036474) ========= 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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009938 build_url: 'https://queue.taskcluster.net/v1/task/0ffqsuCvTfi3qFVyPEVWKA/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-21 04:08:03.086923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 04:08:03.087252) ========= 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=e10134013339c31d8d0c4708000001d6-1448105920.251385-279926419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004609 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 04:08:03.136995) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-21 04:08:03.137309) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-21 04:08:03.137592) ========= ========= Total master_lag: 0.25 =========