builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-046 starttime: 1448330364.75 results: success (0) buildid: 20151123163001 builduid: f7a6c297645f47539aaaace126d55e64 revision: 19d89caa664dd9309c796929225ee409df5d1ee4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.754820) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.755266) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.755598) ========= 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006416 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.792672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.792993) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.865451) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.865754) ========= 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015483 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.914821) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.915149) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.915554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:24.915859) ========= 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 17:59:25-- 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% 219M=0s 2015-11-23 17:59:25 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.214277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:25.146715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:25.147044) ========= 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.079098 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:25.239988) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:25.240346) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 19d89caa664dd9309c796929225ee409df5d1ee4 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 19d89caa664dd9309c796929225ee409df5d1ee4 --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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 17:59:25,411 truncating revision to first 12 chars 2015-11-23 17:59:25,411 Setting DEBUG logging. 2015-11-23 17:59:25,411 attempt 1/10 2015-11-23 17:59:25,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/19d89caa664d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 17:59:26,095 unpacking tar archive at: mozilla-central-19d89caa664d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965584 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:26.220778) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:26.221332) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:26.232896) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:26.233475) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 17:59:26.234222) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-11-23 17:59:26.234782) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:59:26 INFO - MultiFileLogger online at 20151123 17:59:26 in /builds/slave/test-pgo 17:59:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:59:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:59:26 INFO - {'append_to_log': False, 17:59:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:59:26 INFO - 'blob_upload_branch': 'Firefox', 17:59:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:59:26 INFO - 'branch': 'Firefox', 17:59:26 INFO - 'buildbot_json_path': 'buildprops.json', 17:59:26 INFO - 'config_files': ('talos/linux_config.py',), 17:59:26 INFO - 'default_actions': ('clobber', 17:59:26 INFO - 'read-buildbot-config', 17:59:26 INFO - 'download-and-extract', 17:59:26 INFO - 'populate-webroot', 17:59:26 INFO - 'create-virtualenv', 17:59:26 INFO - 'install', 17:59:26 INFO - 'run-tests'), 17:59:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:59:26 INFO - 'download_minidump_stackwalk': True, 17:59:26 INFO - 'download_symbols': 'ondemand', 17:59:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:59:26 INFO - 'tooltool.py': '/tools/tooltool.py', 17:59:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:59:26 INFO - '/tools/misc-python/virtualenv.py')}, 17:59:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:59:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:59:26 INFO - 'installer_path': 'installer.exe', 17:59:26 INFO - 'log_level': 'info', 17:59:26 INFO - 'log_name': 'talos', 17:59:26 INFO - 'log_to_console': True, 17:59:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:59:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:59:26 INFO - 'opt_config_files': (), 17:59:26 INFO - 'pip_index': False, 17:59:26 INFO - 'sps_profile': False, 17:59:26 INFO - 'sps_profile_interval': 0, 17:59:26 INFO - 'suite': 'dromaeojs-e10s', 17:59:26 INFO - 'system_bits': '64', 17:59:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:59:26 INFO - 'title': 'talos-linux64-ix-046', 17:59:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:59:26 INFO - 'use_talos_json': True, 17:59:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:59:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:59:26 INFO - 'work_dir': 'build'} 17:59:26 INFO - ##### 17:59:26 INFO - ##### Running clobber step. 17:59:26 INFO - ##### 17:59:26 INFO - Running pre-action listener: _resource_record_pre_action 17:59:26 INFO - Running main action method: clobber 17:59:26 INFO - rmtree: /builds/slave/test-pgo/build 17:59:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:59:33 INFO - Running post-action listener: _resource_record_post_action 17:59:33 INFO - ##### 17:59:33 INFO - ##### Running read-buildbot-config step. 17:59:33 INFO - ##### 17:59:33 INFO - Running pre-action listener: _resource_record_pre_action 17:59:33 INFO - Running main action method: read_buildbot_config 17:59:33 INFO - Using buildbot properties: 17:59:33 INFO - { 17:59:33 INFO - "properties": { 17:59:33 INFO - "buildnumber": 32, 17:59:33 INFO - "product": "firefox", 17:59:33 INFO - "script_repo_revision": "production", 17:59:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:59:33 INFO - "repository": "", 17:59:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:59:33 INFO - "buildid": "20151123163001", 17:59:33 INFO - "slavename": "talos-linux64-ix-046", 17:59:33 INFO - "pgo_build": "True", 17:59:33 INFO - "basedir": "/builds/slave/test-pgo", 17:59:33 INFO - "project": "", 17:59:33 INFO - "platform": "ubuntu64_hw", 17:59:33 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:59:33 INFO - "slavebuilddir": "test", 17:59:33 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 17:59:33 INFO - "branch": "mozilla-central", 17:59:33 INFO - "repo_path": "mozilla-central", 17:59:33 INFO - "stage_platform": "linux64-pgo", 17:59:33 INFO - "builduid": "f7a6c297645f47539aaaace126d55e64", 17:59:33 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 17:59:33 INFO - }, 17:59:33 INFO - "sourcestamp": { 17:59:33 INFO - "repository": "", 17:59:33 INFO - "hasPatch": false, 17:59:33 INFO - "project": "", 17:59:33 INFO - "branch": "mozilla-central-linux64-pgo-talos", 17:59:33 INFO - "changes": [ 17:59:33 INFO - { 17:59:33 INFO - "category": null, 17:59:33 INFO - "files": [ 17:59:33 INFO - { 17:59:33 INFO - "url": null, 17:59:33 INFO - "name": "https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 17:59:33 INFO - } 17:59:33 INFO - ], 17:59:33 INFO - "repository": "", 17:59:33 INFO - "rev": "19d89caa664dd9309c796929225ee409df5d1ee4", 17:59:33 INFO - "who": "sendchange", 17:59:33 INFO - "when": 1448330271, 17:59:33 INFO - "number": 6698370, 17:59:33 INFO - "comments": "", 17:59:33 INFO - "project": "", 17:59:33 INFO - "at": "Mon 23 Nov 2015 17:57:51", 17:59:33 INFO - "branch": "mozilla-central-linux64-pgo-talos", 17:59:33 INFO - "revlink": "", 17:59:33 INFO - "properties": [ 17:59:33 INFO - [ 17:59:33 INFO - "buildid", 17:59:33 INFO - "20151123163001", 17:59:33 INFO - "Change" 17:59:33 INFO - ], 17:59:33 INFO - [ 17:59:33 INFO - "builduid", 17:59:33 INFO - "f7a6c297645f47539aaaace126d55e64", 17:59:33 INFO - "Change" 17:59:33 INFO - ], 17:59:33 INFO - [ 17:59:33 INFO - "pgo_build", 17:59:33 INFO - "True", 17:59:33 INFO - "Change" 17:59:33 INFO - ] 17:59:33 INFO - ], 17:59:33 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 17:59:33 INFO - } 17:59:33 INFO - ], 17:59:33 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 17:59:33 INFO - } 17:59:33 INFO - } 17:59:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 17:59:33 INFO - Running post-action listener: _resource_record_post_action 17:59:33 INFO - ##### 17:59:33 INFO - ##### Running download-and-extract step. 17:59:33 INFO - ##### 17:59:33 INFO - Running pre-action listener: _resource_record_pre_action 17:59:33 INFO - Running main action method: download_and_extract 17:59:33 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:59:33 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')]} 17:59:33 INFO - https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:59:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/test_packages.json 17:59:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/test_packages.json 17:59:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:59:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:59:33 INFO - Downloaded 1302 bytes. 17:59:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:59:33 INFO - Using the following test package requirements: 17:59:33 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 17:59:33 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:59:33 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'jsshell-linux-x86_64.zip'], 17:59:33 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:59:33 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 17:59:33 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:59:33 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:59:33 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:59:33 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 17:59:33 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:33 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:59:33 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:59:33 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')]} 17:59:33 INFO - https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:59:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 17:59:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 17:59:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:37 INFO - Downloaded 22525861 bytes. 17:59:37 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 17:59:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 17:59:37 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 17:59:38 INFO - Return code: 0 17:59:38 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 17:59:38 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')]} 17:59:38 INFO - https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:59:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 17:59:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 17:59:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:45 INFO - Downloaded 22525861 bytes. 17:59:45 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 17:59:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 17:59:45 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 17:59:46 INFO - Return code: 0 17:59:46 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')]} 17:59:46 INFO - https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:59:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 17:59:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 17:59:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:47 INFO - Downloaded 11130997 bytes. 17:59:47 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 17:59:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 17:59:47 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 17:59:47 INFO - Return code: 0 17:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:47 INFO - https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:59:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 17:59:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 17:59:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/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 17:59:49 INFO - Downloaded 57548023 bytes. 17:59:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 17:59:49 INFO - mkdir: /builds/slave/test-pgo/properties 17:59:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:59:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:59:49 INFO - Contents: 17:59:49 INFO - build_url:https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 17:59:49 INFO - Running post-action listener: _resource_record_post_action 17:59:49 INFO - Running post-action listener: set_extra_try_arguments 17:59:49 INFO - ##### 17:59:49 INFO - ##### Running populate-webroot step. 17:59:49 INFO - ##### 17:59:49 INFO - Running pre-action listener: _resource_record_pre_action 17:59:49 INFO - Running main action method: populate_webroot 17:59:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:59:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:59:49 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 17:59:49 INFO - '../fennec_ids.txt'], 17:59:49 INFO - 'tests': ['tcheck2']}, 17:59:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:59:49 INFO - '--tppagecycles', 17:59:49 INFO - '7'], 17:59:49 INFO - 'tests': ['tsvgm']}}, 17:59:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:59:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:59:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:59:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:59:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:59:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:59:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'tests': ['damp', 'tps']}, 17:59:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['damp', 'tps']}, 17:59:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:59:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:59:49 INFO - 'other': {'tests': ['a11yr', 17:59:49 INFO - 'ts_paint', 17:59:49 INFO - 'tpaint', 17:59:49 INFO - 'sessionrestore', 17:59:49 INFO - 'sessionrestore_no_auto_restore']}, 17:59:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['a11yr', 17:59:49 INFO - 'ts_paint', 17:59:49 INFO - 'tpaint', 17:59:49 INFO - 'sessionrestore', 17:59:49 INFO - 'sessionrestore_no_auto_restore']}, 17:59:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['a11yr', 17:59:49 INFO - 'ts_paint', 17:59:49 INFO - 'tpaint', 17:59:49 INFO - 'sessionrestore', 17:59:49 INFO - 'sessionrestore_no_auto_restore']}, 17:59:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['a11yr', 17:59:49 INFO - 'ts_paint', 17:59:49 INFO - 'tpaint', 17:59:49 INFO - 'sessionrestore', 17:59:49 INFO - 'sessionrestore_no_auto_restore']}, 17:59:49 INFO - 'other_l64': {'tests': ['a11yr', 17:59:49 INFO - 'ts_paint', 17:59:49 INFO - 'tpaint', 17:59:49 INFO - 'sessionrestore', 17:59:49 INFO - 'sessionrestore_no_auto_restore']}, 17:59:49 INFO - 'other_nol64': {'tests': ['a11yr', 17:59:49 INFO - 'ts_paint', 17:59:49 INFO - 'tpaint', 17:59:49 INFO - 'sessionrestore', 17:59:49 INFO - 'sessionrestore_no_auto_restore']}, 17:59:49 INFO - 'svgr': {'tests': ['tsvgx', 17:59:49 INFO - 'tsvgr_opacity', 17:59:49 INFO - 'tart', 17:59:49 INFO - 'tscrollx', 17:59:49 INFO - 'cart']}, 17:59:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['tsvgx', 17:59:49 INFO - 'tsvgr_opacity', 17:59:49 INFO - 'tart', 17:59:49 INFO - 'tscrollx', 17:59:49 INFO - 'cart']}, 17:59:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'tests': ['tp5o']}, 17:59:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'talos_options': ['--e10s'], 17:59:49 INFO - 'tests': ['tp5o']}, 17:59:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'talos_options': ['--xperf_path', 17:59:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:49 INFO - 'tests': ['tp5n']}, 17:59:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:49 INFO - 'talos_options': ['--e10s', 17:59:49 INFO - '--xperf_path', 17:59:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:49 INFO - 'tests': ['tp5n']}}, 17:59:49 INFO - 'talos.zip': {'path': '', 17:59:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:59:49 INFO - Running post-action listener: _resource_record_post_action 17:59:49 INFO - ##### 17:59:49 INFO - ##### Running create-virtualenv step. 17:59:49 INFO - ##### 17:59:49 INFO - Running pre-action listener: _resource_record_pre_action 17:59:49 INFO - Running main action method: create_virtualenv 17:59:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:59:49 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 17:59:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:59:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:59:49 INFO - Using real prefix '/usr' 17:59:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:59:49 INFO - Installing distribute.............................................................................................................................................................................................done. 17:59:51 INFO - Installing pip.................done. 17:59:51 INFO - Return code: 0 17:59:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:51 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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 17:59:51 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 17:59:51 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 17:59:51 INFO - Using env: {'DISPLAY': ':0', 17:59:51 INFO - 'HOME': '/home/cltbld', 17:59:51 INFO - 'LANG': 'en_US.UTF-8', 17:59:51 INFO - 'LANGUAGE': 'en_US:en', 17:59:51 INFO - 'LOGNAME': 'cltbld', 17:59:51 INFO - 'MAIL': '/var/mail/cltbld', 17:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:51 INFO - 'MOZ_NO_REMOTE': '1', 17:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:59:51 INFO - 'NO_EM_RESTART': '1', 17:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:59:51 INFO - 'PWD': '/builds/slave/test-pgo', 17:59:51 INFO - 'SHELL': '/bin/bash', 17:59:51 INFO - 'SHLVL': '1', 17:59:51 INFO - 'TERM': 'linux', 17:59:51 INFO - 'TMOUT': '86400', 17:59:51 INFO - 'USER': 'cltbld', 17:59:51 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 17:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:51 INFO - '_': '/tools/buildbot/bin/python'} 17:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:51 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 17:59:51 INFO - Cleaning up... 17:59:51 INFO - Return code: 0 17:59:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:51 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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 17:59:51 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 17:59:51 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 17:59:51 INFO - Using env: {'DISPLAY': ':0', 17:59:51 INFO - 'HOME': '/home/cltbld', 17:59:51 INFO - 'LANG': 'en_US.UTF-8', 17:59:51 INFO - 'LANGUAGE': 'en_US:en', 17:59:51 INFO - 'LOGNAME': 'cltbld', 17:59:51 INFO - 'MAIL': '/var/mail/cltbld', 17:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:51 INFO - 'MOZ_NO_REMOTE': '1', 17:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:59:51 INFO - 'NO_EM_RESTART': '1', 17:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:59:51 INFO - 'PWD': '/builds/slave/test-pgo', 17:59:51 INFO - 'SHELL': '/bin/bash', 17:59:51 INFO - 'SHLVL': '1', 17:59:51 INFO - 'TERM': 'linux', 17:59:51 INFO - 'TMOUT': '86400', 17:59:51 INFO - 'USER': 'cltbld', 17:59:51 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 17:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:51 INFO - '_': '/tools/buildbot/bin/python'} 17:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:52 INFO - Downloading/unpacking psutil>=0.7.1 17:59:52 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 17:59:52 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 17:59:52 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 17:59:52 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 17:59:52 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 17:59:52 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 17:59:53 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 17:59:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:59:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:59:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:59:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:59:53 INFO - Installing collected packages: psutil 17:59:53 INFO - Running setup.py install for psutil 17:59:54 INFO - building 'psutil._psutil_linux' extension 17:59:54 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 17:59:54 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 17:59:54 INFO - building 'psutil._psutil_posix' extension 17:59:54 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 17:59:54 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 17:59:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:59:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:59:54 INFO - Successfully installed psutil 17:59:54 INFO - Cleaning up... 17:59:54 INFO - Return code: 0 17:59:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:54 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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 17:59:54 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 17:59:54 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 17:59:54 INFO - Using env: {'DISPLAY': ':0', 17:59:54 INFO - 'HOME': '/home/cltbld', 17:59:54 INFO - 'LANG': 'en_US.UTF-8', 17:59:54 INFO - 'LANGUAGE': 'en_US:en', 17:59:54 INFO - 'LOGNAME': 'cltbld', 17:59:54 INFO - 'MAIL': '/var/mail/cltbld', 17:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:54 INFO - 'MOZ_NO_REMOTE': '1', 17:59:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:59:54 INFO - 'NO_EM_RESTART': '1', 17:59:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:59:54 INFO - 'PWD': '/builds/slave/test-pgo', 17:59:54 INFO - 'SHELL': '/bin/bash', 17:59:54 INFO - 'SHLVL': '1', 17:59:54 INFO - 'TERM': 'linux', 17:59:54 INFO - 'TMOUT': '86400', 17:59:54 INFO - 'USER': 'cltbld', 17:59:54 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 17:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:54 INFO - '_': '/tools/buildbot/bin/python'} 17:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:59:54 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 17:59:54 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 17:59:54 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 17:59:54 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 17:59:54 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 17:59:54 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 17:59:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:59:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:59:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:59:56 INFO - Installing collected packages: mozsystemmonitor 17:59:56 INFO - Running setup.py install for mozsystemmonitor 17:59:56 INFO - Successfully installed mozsystemmonitor 17:59:56 INFO - Cleaning up... 17:59:56 INFO - Return code: 0 17:59:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:56 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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 17:59:56 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 17:59:56 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 17:59:56 INFO - Using env: {'DISPLAY': ':0', 17:59:56 INFO - 'HOME': '/home/cltbld', 17:59:56 INFO - 'LANG': 'en_US.UTF-8', 17:59:56 INFO - 'LANGUAGE': 'en_US:en', 17:59:56 INFO - 'LOGNAME': 'cltbld', 17:59:56 INFO - 'MAIL': '/var/mail/cltbld', 17:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:56 INFO - 'MOZ_NO_REMOTE': '1', 17:59:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:59:56 INFO - 'NO_EM_RESTART': '1', 17:59:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:59:56 INFO - 'PWD': '/builds/slave/test-pgo', 17:59:56 INFO - 'SHELL': '/bin/bash', 17:59:56 INFO - 'SHLVL': '1', 17:59:56 INFO - 'TERM': 'linux', 17:59:56 INFO - 'TMOUT': '86400', 17:59:56 INFO - 'USER': 'cltbld', 17:59:56 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 17:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:56 INFO - '_': '/tools/buildbot/bin/python'} 17:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:56 INFO - Downloading/unpacking blobuploader==1.2.4 17:59:56 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 17:59:56 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 17:59:56 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 17:59:56 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 17:59:56 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 17:59:56 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 17:59:58 INFO - Downloading blobuploader-1.2.4.tar.gz 17:59:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:59:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:59:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:59:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:59:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 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 17:59:58 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 17:59:59 INFO - Downloading docopt-0.6.1.tar.gz 17:59:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:59:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:59:59 INFO - Installing collected packages: blobuploader, requests, docopt 17:59:59 INFO - Running setup.py install for blobuploader 17:59:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:59:59 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:59:59 INFO - Running setup.py install for requests 17:59:59 INFO - Running setup.py install for docopt 17:59:59 INFO - Successfully installed blobuploader requests docopt 17:59:59 INFO - Cleaning up... 17:59:59 INFO - Return code: 0 17:59:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:59 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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 17:59:59 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 17:59:59 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 17:59:59 INFO - Using env: {'DISPLAY': ':0', 17:59:59 INFO - 'HOME': '/home/cltbld', 17:59:59 INFO - 'LANG': 'en_US.UTF-8', 17:59:59 INFO - 'LANGUAGE': 'en_US:en', 17:59:59 INFO - 'LOGNAME': 'cltbld', 17:59:59 INFO - 'MAIL': '/var/mail/cltbld', 17:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:59 INFO - 'MOZ_NO_REMOTE': '1', 17:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:59:59 INFO - 'NO_EM_RESTART': '1', 17:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:59:59 INFO - 'PWD': '/builds/slave/test-pgo', 17:59:59 INFO - 'SHELL': '/bin/bash', 17:59:59 INFO - 'SHLVL': '1', 17:59:59 INFO - 'TERM': 'linux', 17:59:59 INFO - 'TMOUT': '86400', 17:59:59 INFO - 'USER': 'cltbld', 17:59:59 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 17:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:59 INFO - '_': '/tools/buildbot/bin/python'} 17:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:59:59 INFO - Running setup.py (path:/tmp/pip-3o3ZMT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:00:00 INFO - Running setup.py (path:/tmp/pip-BbLeMY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:00:00 INFO - Running setup.py (path:/tmp/pip-VUea_I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:00:00 INFO - Running setup.py (path:/tmp/pip-mv_LUt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:00:00 INFO - Running setup.py (path:/tmp/pip-oRDvMo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:00:00 INFO - Running setup.py (path:/tmp/pip-QjrqsX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:00:00 INFO - Running setup.py (path:/tmp/pip-VgoRDg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:00:00 INFO - Running setup.py (path:/tmp/pip-lEBqp6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:00:00 INFO - Running setup.py (path:/tmp/pip-_ZDFCP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:00:00 INFO - Running setup.py (path:/tmp/pip-CbMxjp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:00:00 INFO - Running setup.py (path:/tmp/pip-Uf_UwO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:00:00 INFO - Running setup.py (path:/tmp/pip-e4Jm6i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:00:00 INFO - Running setup.py (path:/tmp/pip-G6mjJK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:00:00 INFO - Running setup.py (path:/tmp/pip-bvrIrk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:00:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:00:00 INFO - Running setup.py (path:/tmp/pip-l2nFHv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:00:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:00:01 INFO - Running setup.py (path:/tmp/pip-D2Pt7C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:00:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:00:01 INFO - Running setup.py (path:/tmp/pip-et5HCq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:00:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:00:01 INFO - Running setup.py install for manifestparser 18:00:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:00:01 INFO - Running setup.py install for mozcrash 18:00:01 INFO - Running setup.py install for mozdebug 18:00:01 INFO - Running setup.py install for mozdevice 18:00:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:00:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:00:01 INFO - Running setup.py install for mozfile 18:00:01 INFO - Running setup.py install for mozhttpd 18:00:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:00:01 INFO - Running setup.py install for mozinfo 18:00:01 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:00:01 INFO - Running setup.py install for mozInstall 18:00:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Running setup.py install for mozleak 18:00:02 INFO - Running setup.py install for mozlog 18:00:02 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Running setup.py install for moznetwork 18:00:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Running setup.py install for mozprocess 18:00:02 INFO - Running setup.py install for mozprofile 18:00:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:00:02 INFO - Running setup.py install for mozrunner 18:00:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:00:03 INFO - Running setup.py install for mozscreenshot 18:00:03 INFO - Running setup.py install for moztest 18:00:03 INFO - Running setup.py install for mozversion 18:00:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:00:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:00:03 INFO - Cleaning up... 18:00:03 INFO - Return code: 0 18:00:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 18:00:03 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 18:00:03 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 18:00:03 INFO - Using env: {'DISPLAY': ':0', 18:00:03 INFO - 'HOME': '/home/cltbld', 18:00:03 INFO - 'LANG': 'en_US.UTF-8', 18:00:03 INFO - 'LANGUAGE': 'en_US:en', 18:00:03 INFO - 'LOGNAME': 'cltbld', 18:00:03 INFO - 'MAIL': '/var/mail/cltbld', 18:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:03 INFO - 'MOZ_NO_REMOTE': '1', 18:00:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:03 INFO - 'NO_EM_RESTART': '1', 18:00:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:00:03 INFO - 'PWD': '/builds/slave/test-pgo', 18:00:03 INFO - 'SHELL': '/bin/bash', 18:00:03 INFO - 'SHLVL': '1', 18:00:03 INFO - 'TERM': 'linux', 18:00:03 INFO - 'TMOUT': '86400', 18:00:03 INFO - 'USER': 'cltbld', 18:00:03 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 18:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:03 INFO - '_': '/tools/buildbot/bin/python'} 18:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:00:03 INFO - Running setup.py (path:/tmp/pip-8297H0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:00:03 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)) 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:00:03 INFO - Running setup.py (path:/tmp/pip-q_nlH3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:00:03 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)) 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:00:03 INFO - Running setup.py (path:/tmp/pip-tUaXDO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:00:03 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)) 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:00:03 INFO - Running setup.py (path:/tmp/pip-h72MSM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:00:03 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)) 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:00:03 INFO - Running setup.py (path:/tmp/pip-na4SEj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:00:03 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)) 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:00:03 INFO - Running setup.py (path:/tmp/pip-qo4_hB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:00:03 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)) 18:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:00:03 INFO - Running setup.py (path:/tmp/pip-UORmqF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:00:04 INFO - Running setup.py (path:/tmp/pip-vZbjP2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:00:04 INFO - Running setup.py (path:/tmp/pip-Bzvo40-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:00:04 INFO - Running setup.py (path:/tmp/pip-NUKobK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:00:04 INFO - Running setup.py (path:/tmp/pip-dzyf95-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:00:04 INFO - Running setup.py (path:/tmp/pip-iHWdl4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:00:04 INFO - Running setup.py (path:/tmp/pip-Y3wQ__-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:00:04 INFO - Running setup.py (path:/tmp/pip-kJDg0S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:00:04 INFO - Running setup.py (path:/tmp/pip-QQ2oyJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:00:04 INFO - Running setup.py (path:/tmp/pip-oc6BU8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:00:04 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)) 18:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:00:04 INFO - Running setup.py (path:/tmp/pip-R93J6T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:00:04 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)) 18:00:04 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)) 18:00:04 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)) 18:00:04 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)) 18:00:04 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)) 18:00:04 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)) 18:00:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:06 INFO - Downloading blessings-1.5.1.tar.gz 18:00:06 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 18:00:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:00:06 INFO - Installing collected packages: blessings 18:00:06 INFO - Running setup.py install for blessings 18:00:06 INFO - Successfully installed blessings 18:00:06 INFO - Cleaning up... 18:00:06 INFO - Return code: 0 18:00:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:00:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:00:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:00:06 INFO - Reading from file tmpfile_stdout 18:00:06 INFO - Current package versions: 18:00:06 INFO - argparse == 1.2.1 18:00:06 INFO - blessings == 1.5.1 18:00:06 INFO - blobuploader == 1.2.4 18:00:06 INFO - docopt == 0.6.1 18:00:06 INFO - manifestparser == 1.1 18:00:06 INFO - mozInstall == 1.12 18:00:06 INFO - mozcrash == 0.16 18:00:06 INFO - mozdebug == 0.1 18:00:06 INFO - mozdevice == 0.47 18:00:06 INFO - mozfile == 1.2 18:00:06 INFO - mozhttpd == 0.7 18:00:06 INFO - mozinfo == 0.9 18:00:06 INFO - mozleak == 0.1 18:00:06 INFO - mozlog == 3.1 18:00:06 INFO - moznetwork == 0.27 18:00:06 INFO - mozprocess == 0.22 18:00:06 INFO - mozprofile == 0.27 18:00:06 INFO - mozrunner == 6.11 18:00:06 INFO - mozscreenshot == 0.1 18:00:06 INFO - mozsystemmonitor == 0.0 18:00:06 INFO - moztest == 0.7 18:00:06 INFO - mozversion == 1.4 18:00:06 INFO - psutil == 3.1.1 18:00:06 INFO - requests == 1.2.3 18:00:06 INFO - wsgiref == 0.1.2 18:00:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:06 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 0x7f9ef3c07c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, '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': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', '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 18:00:06 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 18:00:06 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 18:00:06 INFO - Using env: {'DISPLAY': ':0', 18:00:06 INFO - 'HOME': '/home/cltbld', 18:00:06 INFO - 'LANG': 'en_US.UTF-8', 18:00:06 INFO - 'LANGUAGE': 'en_US:en', 18:00:06 INFO - 'LOGNAME': 'cltbld', 18:00:06 INFO - 'MAIL': '/var/mail/cltbld', 18:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:06 INFO - 'MOZ_NO_REMOTE': '1', 18:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:06 INFO - 'NO_EM_RESTART': '1', 18:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:00:06 INFO - 'PWD': '/builds/slave/test-pgo', 18:00:06 INFO - 'SHELL': '/bin/bash', 18:00:06 INFO - 'SHLVL': '1', 18:00:06 INFO - 'TERM': 'linux', 18:00:06 INFO - 'TMOUT': '86400', 18:00:06 INFO - 'USER': 'cltbld', 18:00:06 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 18:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:06 INFO - '_': '/tools/buildbot/bin/python'} 18:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 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)) 18:00:07 INFO - Cleaning up... 18:00:07 INFO - Return code: 0 18:00:07 INFO - Running post-action listener: _resource_record_post_action 18:00:07 INFO - Running post-action listener: _start_resource_monitoring 18:00:07 INFO - Starting resource monitoring. 18:00:07 INFO - ##### 18:00:07 INFO - ##### Running install step. 18:00:07 INFO - ##### 18:00:07 INFO - Running pre-action listener: _resource_record_pre_action 18:00:07 INFO - Running main action method: install 18:00:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:00:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:00:07 INFO - Reading from file tmpfile_stdout 18:00:07 INFO - Detecting whether we're running mozinstall >=1.0... 18:00:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:00:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:00:07 INFO - Reading from file tmpfile_stdout 18:00:07 INFO - Output received: 18:00:07 INFO - Usage: mozinstall [options] installer 18:00:07 INFO - Options: 18:00:07 INFO - -h, --help show this help message and exit 18:00:07 INFO - -d DEST, --destination=DEST 18:00:07 INFO - Directory to install application into. [default: 18:00:07 INFO - "/builds/slave/test-pgo"] 18:00:07 INFO - --app=APP Application being installed. [default: firefox] 18:00:07 INFO - mkdir: /builds/slave/test-pgo/build/application 18:00:07 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'] 18:00:07 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 18:00:21 INFO - Reading from file tmpfile_stdout 18:00:21 INFO - Output received: 18:00:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:00:21 INFO - Running post-action listener: _resource_record_post_action 18:00:21 INFO - ##### 18:00:21 INFO - ##### Running run-tests step. 18:00:21 INFO - ##### 18:00:21 INFO - Running pre-action listener: _resource_record_pre_action 18:00:21 INFO - Running main action method: run_tests 18:00:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:00:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:00:21 INFO - Python 2.7.3 18:00:21 INFO - Return code: 0 18:00:21 INFO - grabbing minidump binary from tooltool 18:00: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')]} 18:00:21 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 0x19e2d70>, '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 0x19e3b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e3fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:00:21 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 18:00:21 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 18:00:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:00:21 INFO - Return code: 0 18:00:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:00:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:00:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:00:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:00:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:00:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:00:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 18:00:21 INFO - Using env: {'DISPLAY': ':0', 18:00:21 INFO - 'HOME': '/home/cltbld', 18:00:21 INFO - 'LANG': 'en_US.UTF-8', 18:00:21 INFO - 'LANGUAGE': 'en_US:en', 18:00:21 INFO - 'LOGNAME': 'cltbld', 18:00:21 INFO - 'MAIL': '/var/mail/cltbld', 18:00:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:00:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:21 INFO - 'MOZ_NO_REMOTE': '1', 18:00:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:21 INFO - 'NO_EM_RESTART': '1', 18:00:21 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:00:21 INFO - 'PWD': '/builds/slave/test-pgo', 18:00:21 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:00:21 INFO - 'SHELL': '/bin/bash', 18:00:21 INFO - 'SHLVL': '1', 18:00:21 INFO - 'TERM': 'linux', 18:00:21 INFO - 'TMOUT': '86400', 18:00:21 INFO - 'USER': 'cltbld', 18:00:21 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287', 18:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:21 INFO - '_': '/tools/buildbot/bin/python'} 18:00:21 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 18:00:22 INFO - mozversion INFO | application_buildid: 20151123163001 18:00:22 INFO - mozversion INFO | application_changeset: 19d89caa664dd9309c796929225ee409df5d1ee4 18:00:22 INFO - mozversion INFO | application_display_name: Nightly 18:00:22 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:00:22 INFO - mozversion INFO | application_name: Firefox 18:00:22 INFO - mozversion INFO | application_remotingname: firefox 18:00:22 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:00:22 INFO - mozversion INFO | application_vendor: Mozilla 18:00:22 INFO - mozversion INFO | application_version: 45.0a1 18:00:22 INFO - mozversion INFO | platform_buildid: 20151123163001 18:00:22 INFO - mozversion INFO | platform_changeset: 19d89caa664dd9309c796929225ee409df5d1ee4 18:00:22 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:00:22 INFO - mozversion INFO | platform_version: 45.0a1 18:00:22 INFO - 2015-11-23 18:00:22,141 DEBUG : using testdate: 1448330422 18:00:22 INFO - 2015-11-23 18:00:22,141 DEBUG : actual date: 1448330422 18:00:22 INFO - 2015-11-23 18:00:22,232 INFO : starting webserver on 'localhost:44558' 18:00:22 INFO - 2015-11-23 18:00:22,233 INFO : Starting test suite talos-linux64-ix-046.e 18:00:22 INFO - 2015-11-23 18:00:22,233 INFO : Starting test dromaeo_css 18:00:22 INFO - 2015-11-23 18:00:22,233 DEBUG : operating with platform_type : linux_ 18:00:22 INFO - 2015-11-23 18:00:22,234 INFO : Initialising browser for dromaeo_css test... 18:00:22 INFO - 2015-11-23 18:00:22,247 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIf46l0/profile http://localhost:44558/getInfo.html 18:00:26 INFO - 2015-11-23 18:00:26,679 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:00:26 INFO - 2015-11-23 18:00:26,679 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:00:26 INFO - 2015-11-23 18:00:26,679 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:00:26 INFO - 2015-11-23 18:00:26,679 DEBUG : BROWSER_OUTPUT: __metrics 18:00:27 INFO - 2015-11-23 18:00:27,693 INFO : Browser initialized. 18:00:27 INFO - 2015-11-23 18:00:27,694 INFO : Running cycle 1/1 for dromaeo_css test... 18:00:27 INFO - 2015-11-23 18:00:27,694 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIf46l0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:00:39 INFO - 2015-11-23 18:00:39,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 140075008 18:00:39 INFO - 2015-11-23 18:00:39,490 DEBUG : BROWSER_OUTPUT: 18:01:29 INFO - 2015-11-23 18:01:29,113 DEBUG : BROWSER_OUTPUT: 1448330489109 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] 18:03:38 INFO - 2015-11-23 18:03:38,212 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44558/tests/dromaeo/cssquery-ext.html) 18:03:39 INFO - 2015-11-23 18:03:39,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 147181568 18:03:39 INFO - 2015-11-23 18:03:39,540 DEBUG : BROWSER_OUTPUT: 18:06:41 INFO - 2015-11-23 18:06:41,564 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/dromaeo/cssquery-ext.html (next: http://localhost:44558/tests/dromaeo/cssquery-jquery.html) 18:06:42 INFO - 2015-11-23 18:06:42,165 DEBUG : BROWSER_OUTPUT: RSS: Main: 142295040 18:06:42 INFO - 2015-11-23 18:06:42,165 DEBUG : BROWSER_OUTPUT: 18:09:43 INFO - 2015-11-23 18:09:43,838 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44558/tests/dromaeo/cssquery-mootools.html) 18:09:44 INFO - 2015-11-23 18:09:44,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 144154624 18:09:44 INFO - 2015-11-23 18:09:44,431 DEBUG : BROWSER_OUTPUT: 18:12:46 INFO - 2015-11-23 18:12:46,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44558/tests/dromaeo/cssquery-prototype.html) 18:12:46 INFO - 2015-11-23 18:12:46,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 140546048 18:12:46 INFO - 2015-11-23 18:12:46,656 DEBUG : BROWSER_OUTPUT: 18:15:48 INFO - 2015-11-23 18:15:48,907 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44558/tests/dromaeo/cssquery-yui.html) 18:15:49 INFO - 2015-11-23 18:15:49,502 DEBUG : BROWSER_OUTPUT: RSS: Main: 140947456 18:15:49 INFO - 2015-11-23 18:15:49,502 DEBUG : BROWSER_OUTPUT: 18:18:51 INFO - 2015-11-23 18:18:51,907 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/dromaeo/cssquery-yui.html (next: http://localhost:44558/tests/dromaeo/cssquery-dojo.html) 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 144277504 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3200.9679903200968;3290.9999999999986;3309.9573634305175;3334.549835772171;3336.432806422908;5335.946640533593;5525.171224316355;5554.250176226204;5565.053940830068;5572.8606784830345;5333;5395.946040539593;5411.756470958816;5458.699771512565;5532.2531458253125;4951.1830547959635;5037.370328708911;5084.745762711849;5112.642115051948;5113.923291150636;4992.126377883874;5015.448300686921;5049.040682270357;5065.721385323805;5077.933633936877;4913.631477639191;4927.6304277179;5037.967216720582;5040.647154699154;5052.368453943257;5007.724575148365;5010.574101201402;5015.899682006376;5034.446210916796;5077.517635824591;5008.7996480140755;5010.348654674905;5012.674176178537;5016.222485514714;5043.621728370387;5081.28861959326;5140.820071297487;5165.896682066337;5235;5285.207218917155;5902.498287645555;6020.608660437101;6021.2473440819895;6022.5483088768515;6030.758769649209;6000.219971403733;6004.969975150097;6012.158297838306;6018.759249630009;6024.969875150597;5413.783448662049;5467.781288748485;5584.190292407578;5591.916121258185;5603.915941260884;6048;6051.880402125621;6057.969710151421;6059.8485037873525;6106.236720409949;5824.563157763185;5879.794207202813;5893.528517718572;5895.55783316253;5907.822765317047;5801.3038435388025;5851.824445266649;5871.94128058714;5949.494292985059;5972.343042265347;5847.853803654943;5856.912146317809;5863.941360586425;5865.824025279248;5953.434423729739;5804.158397032406;5884.352721200665;5884.440978107073;5890.057590785454;5898.941010589925;4399.6920215584605;4495.730256184574;4495.88760280989;4538.52345503724;4610.308453731967;4081.612246836605;4085.816138273785;4088.202800453883;4094.160697057096;4125.690573207022;5702.686352250583;5702.686352250583;5706.457886500776;5730.140478928194;5781.4507621775865;5808.041673123972;5872.236609240771;5957.404259574095;5981.820545383645;6003.159557661931;5662.915056274159;5702.0591602385975;5733.627314224562;5747;5749.310082790092;4874.805007799649;5020.397552293748;5066.721330326794;5075.619328550373;5127.333446651986;5991;6041.063635136561;6048.42539958712;6099.2985806632805;6127.080937859357;5915.438033386821;5923.49650279731;5954.642721436728;5957.821265362046;5961.016432288624;5967.9701601491715;6088.512918966383;6138.570300078952;6139.693015349304;6149.907751383822;5729.971350143389;5811.244538210006;5811.476967072984;5844.298684157929;5866.442687944638;5880.206172166702;5977.850553736192;5977.9103313451205;5998.970005149947;6003.759849605967;5630.690312032878;5712.028955077563;5765.884682306414;5832;5843.590948633555;5808.448197421239;5856.970715146397;5857;5881.500072493886;5892.970535147469;5678.659280443187;5682.857928551819;5690.345610254708;5712.942870571241;5737.741801618854;5601.915971260353;5756.0502517084215;5764.59647824665;5775.797847075332;5829.271341082364;5565.805196818092;5644.887102258014;5656.604037717485;5664.150377443417;5682.6306290091015 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: |1;ext.html;560.4451592923004;562.7495764384851;578.5400606517818;581.1254062635733;604.1029071828332;39081.2183756325;40187.598124018754;40274.39588406176;40284.19431611366;40332.19335613286;43824.342634860426;44342.7782861086;44359.33460998088;44375.11249775002;44556.55443445565;8704.912950870505;8888.777780555472;8890.110988901091;8899.154580314893;8922.420042697204;8503.362247831406;8626.698065567707;8664.696735614254;8665.523396213206;8681.522516261603;8727.738167854974;8728.127187281252;8795.296376289882;8817.823643527126;8818.29453643707;8731.388802783807;8773.078826723166;8797.824043519095;8810;8813.294936405073;8614.095519970375;8769.561521923943;8814.911850881474;8817.338699597492;8825.779355516099;40456.797716011526;40826;40947.59052409497;40956.795216024024;40987.180256394706;3266.3467306538787;3361.647027062172;3521.6654417830264;3548.5741710994844;4018.6383225509553;29677.70322296771;30203.583713302884;30253.697463025528;30626.081217563507;31027.84486077578;3644.2164934538937;3711.981440092782;3739.083924438503;3804.423702108144;3816.9045773855596;264895;265463;265636;265735;266091;51993;58029;58199;58479;58756.41243587509;53418.465815342126;70217.64891175511;70955.29044709487;71127;71274.2872571278;15800;15819.68360632781;15854.365825366856;16007.119608421648;16011.759823602655;12006.63980080599;12137.575184868487;12183.695407614881;12199.207051541622;12229.143959922898;19812.306569270007;20259.29092481756;20340.491487712927;20393.286234981704;20407.285744998855;7744.961275193701;7848.215178482106;7919.524828510308;7949.920500794918;8029;909.2226146644656;990.5294984882267;1133.18410744264;1133.3333333333333;1136.9545218191345;29190.708092919223;29357.706422935924;29906.252343691584;29972.700272997427;30002.24994375158;1005.6982905128432;1021.6066814276558;1043.232339213671;1095.8520599719095;1117.6088369070924;14842.332095055532;14843.480478183212;14875.925620372045;14896.329665165094;15004.174770387515;308604;314509;315381;315469;316097;53653.463465365625;53689.46310536845;53739;53743.46256537385;54064.729676351366;1341.161773891318;1342.637487878287;1346.0577595683062;1353.3503918119359;1360.6190266725332;27166;28066.158015259574;28376.43247135087;28839.855800720863;29763.851180743957;45121.54878451173;45418.545814542755;45467.31799023083;45587;45630.771846141884;217309;218150;218450;218878;219105;28952.565711514766;29500.852495737385;29794.70205298006;29895.850520747255;29936.850315749154;40741.59258407378;43922.34116488319;44393.77803111093;44831.551684484046;44849.327260090475;37275.39320192563;37796.811015944746;38113.428298576095;38392.2321553573;38891;37891;38277.80861095677;38372.23255534929;38675.6132438672;39054.80472597619;36189.457158143174;36935.076623083565;37248.44127338146;37250.627493724714;37337;30943.845280774352;33807.66192338045;34127;34685.826570866986;34771.478427824106;26041.348966275993;26042.34894127586;26250.13699449027;26381.472370552867;26424.471510569296 18:18:52 INFO - 2015-11-23 18:18:52,166 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.3798184186752;353.38863765685346;354.06173639854387;354.65421214316063;355.67278104144185;16375.754363684538;16921.492355229348;17374;17939.551511212227;18035.27858885643;10910.890891091087;11649.883501164986;16550.337986480557;16600.25298861551;16606.335746570152;4742.691725037878;4955.900881982358;4973.975130124344;4975.253711943201;4986.127427700156;4840.806367745304;4921.753912304371;4924.236743304794;4924.433690125627;4937.136001199776;4862;4939.654224204307;4970.353853998975;5081.923771143417;5190.351206099238;5173.948260517422;5185.87035324118;5209.348831396075;5219.164933610623;5223.320968274138;4993.400791904995;5171.793128274864;5208.66143700662;5229.084910140748;5252.501012403815;11888.821667674993;11919.106067044919;12130.211536250115;12396.876031239663;12429;2034.7558293004934;2081.9271325503537;2113.50332671822;2146.302451703187;2179.716636837201;9161.954190229073;9432.434053956784;9869;10504.2647014709;13479.663008424868;1755.8507526860105;1826.963460730778;1843.317972350239;1857.860660450458;1862.4691962790675;60289.22133868455;63048.054279185846;63310;64172.679136604485;65204.3479565203;10138.543765530569;10378.532966016397;10397.220208484394;10408.115310198567;10433.5826566938;13508.459661613426;13530.18818870871;13546.864531354757;13593;13621.387037583332;7787.5716835574585;7794.805129871798;7801.141874393812;7889.921100788919;7898;7497.400207983348;7501.324880760758;7551.773446796605;7610.505317154367;7625.809354766176;4408.867733967986;4436.977815110969;4455.465344158657;4462.44219472566;4479.686421950432;1818.7999320074782;1821.799602043774;1830.6704793137362;1841.3371186372894;1850.8796928199893;404.58530006742734;425.3237352609304;430.55007517144657;438.9451318585177;952.2524818017823;10001.749956251151;10388.636397726043;10589;10637.414942178255;10724;417.1219582778208;423.9766812825263;424.67936707785424;433.98698039058877;435.8104224662282;9486.478243696663;9992.700218993441;10028.448435336126;10133.797324053477;10199.796004080024;534293;538259;538660;545654;550161;73888.26111738921;75844.62077689577;77306.61346693231;77611;79202;1060.2790102730216;1105.7622611138643;1114.4483480676954;1125.2179735084037;1141.0529260713422;14495.20276384809;15526.689466210386;15564.29960651796;15630.765538516704;15881.68236635284;34167.4874876882;34418.655813441546;34808.47787283142;35599.46600801042;36162.8191859039;42789;44575.10849783051;44691.55308446874;46913;48161.27758083701;8590.613422395882;8951.865722014045;9038.096190381173;9058.36591438593;9067.90932090697;10225.43760093202;11681.649550513499;16848.57878553046;17140.142992850557;17527.737083943495;13540;13784.379702913193;14026.859731402963;14090.929545352208;14516.564503064925;13686.384112714753;14091.013629045869;14199;14461.638459038608;14973.700525989638;14383.208923509308;14979;15077.321520531383;15312.234388280764;15822.68354632924;9035.277177825626;9416.81166376655;9424.481653509123;9456.101670341306;9840.065193806864;13952.511662091778;14161.91562143647;14674.70650586961;14728.263586820402;14999.475018374742 18:18:52 INFO - 2015-11-23 18:18:52,170 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1421.651695334643;1481.881449484041;1606.5260748079315;1629.8859079864415;1770.8671849611274;6824.454043676506;6886.586804791716;7385.852282954338;7493.138289096755;7532.096148462186;7329.56022638642;7477.73827916023;7547.886781698266;7558.735444259452;7579.4694371394025;2251.6735073414384;2284.2233640562204;2293.0598454633605;2293.3234695764704;2293.3807871874583;2221.1892659179443;2221.378014156031;2236.329101269621;2251.2008237075847;2262.049939025605;2241;2260.8078313343385;2266.410733209369;2271.3980795089315;2302.286291249718;2259.5819773341987;2297.9770202298014;2337.672725818387;2337.859728416292;2341.7072865891764;2313.109452860644;2342.3090188394463;2344.284993077111;2344.765523447659;2345.7185137783576;7817.804554886174;7845.097813751435;7852.057753069668;7852.411069169778;7872.921270787276;4635.049814787961;4737.336772851769;4742.478327383985;4744.952550474485;4755.096531659008;3134.106779567835;3171.714545690899;3209.7111259986714;3246;3269.9182520436943;984.7931934293807;989.3470309595632;994.5723338964242;1015.0914931136665;1035.891231420705;10668.94665526675;10699.518521666465;10705.732356691067;10718.732031699192;10719.571217151304;4242.363645453207;4268.189044081614;4275.850345237902;4294.098239369768;4304.892377690583;9096.90903090974;9116.954415228014;9172.54137293146;9184.63261469547;9190.172884440433;4761.119192949315;4769.2130798417875;4785.856424307276;4788.11419887322;4793.472718001017;3858.093348063203;3893.552241492208;4165.437665915063;4276.31578947367;4317;3584.0502266899293;3609.9458508122398;3616.349057169735;3623.05800491874;3673.7979411132646;1216.2216181643755;1216.9391530423443;1218.8842060004285;1230.0712961714028;1233.6052463211686;918.6279556779515;940.149165005671;943.4433684126399;963.9228861691048;1158.5829101523445;7264.564126152448;7284.635768211569;7296.525725827804;7300.16048154469;7305.744298949511;468.82419092840183;475.8596214116844;484.7915396379591;490.43844797706424;493.7013107070243;5725.055365864669;5757;5788.797392091341;5789.942100579025;5815.476607105296;9306.813863722688;9318.207952323994;9375.296852736072;9441.575129119068;9483.288753343526;9053.68312109073;9247.537623118817;9343.859842102374;9382.95308523453;9389.10803473669;7145.464090193256;7173.1392232932385;7187.712491500386;7281.271872812782;7284.271572842674;6824.771541122447;6889.931100689129;6897.689603967733;6899.758043552209;6907.171139463297;8096.352291816523;8234.629441675019;8313.584320784057;8321.417500774887;8430;8340.207680270365;8390.496570205807;8466.280366168945;8578.528180949857;8581.927259092614;6484.383983521558;6823.658817059028;6827.249002609609;6838.965805170942;6936.0636314098965;7518.812029699301;7531.133919599097;7545.773626791205;7583.05211848519;7585.393168546393;6958.930410696031;7085.645717713991;7141.250168732416;7244.601546914971;7273.490855640054;6820.556663816836;6823.829404264933;6939.063226464363;6939.097917270924;6944.4097251734165;6672.165979252593;6684.63234522106;6996.720131194696;7023.578585284899;7043.2604576518725;6297.653629050446;6348.968255158695;6349.301576826453;6354.142190804182;6362.618242905441;6462.3537646235;6584.4732421405215;6585;6591.373819487141;6608.140941677551 18:18:52 INFO - 2015-11-23 18:18:52,170 DEBUG : BROWSER_OUTPUT: |4;prototype.html;321.72171072022707;328.0535654636374;329.5435821387378;334.75228331035026;339.5500961226374;6266.686665666715;6275.6548389838545;6287.119803227546;6330.65181415022;6437.6781160942;6202.162708034414;6247.9062814057825;6259.906101408483;6261.843453913654;6307.211598550181;4781.187198176301;4781.235002399617;4786.449558300805;4791.089692958344;4793.952060479385;4791.257355109946;4800.639952003597;4806.615470762331;4807.78364973577;4808;4629.907401851961;4694.319323698061;4712.481627021025;4719.056188762244;4745.21703918853;4714.693544919586;4729.2433210686295;4735.668503204777;4739.976300118512;4742.264948932903;4130.112025914443;4136.482939632546;4139.048018955657;4141.316682747343;4148.792560372;5898.41015898411;5899.203607513016;5936.821895343147;5939;5953.404659534055;5413.512783849433;5553.111502159675;5567.081431563827;5569.554435645138;5585.776568937237;6583.144191255106;6616.073749675002;6628.502862285348;6629.237637671633;6633.369829866155;4592.862214133548;4593.655475839326;4603.987122832971;4607.147677679606;4611.100835337111;6739.59562426256;6849.1096157499705;6880.759173428906;6901.275366086539;6912.758053468155;4688.203005489075;4701.529847015339;4702.506236845082;4710.905781884411;4714.787834547454;4772.785224664898;4780.521947805191;4782.043591281688;4782.254477115827;4782.760861956958;5536.031194541019;5549.611527193058;5551.944480555143;5553.694546799884;5555.583331250173;6586.637734924625;6652.434543063894;6667.733290668319;6677.065210870482;6678.298778628271;1656.4782093640465;1683.099541745185;1699.53262852714;1700.4728534154203;1704.3523461084708;1119.9832002519968;1125.2123513540555;1134.5688638317386;1137.766757814646;1146.856642919635;6353.491720662336;6358.237011558643;6370.649614271167;6410.262819775692;6417.711202995783;6726.831829204309;6736.05695202672;6744.224414192333;6764.086848275519;6794.626295553792;6335.809925702236;6387.808365749035;6392.328805475451;6400.679966001681;6416.647084410309;3718.330700473941;3736.5142531470733;3740.532433445819;3745.606711295329;3764.981175094107;7130.821729456805;7210;7256.6734496947765;7266.927330726625;7298.817529561803;7032.226455089936;7185.389241914496;7185.993960845486;7186.964065179641;7220.4584656151;7205.963970180221;7251.8187045324285;7253.528520646141;7260.673269702982;7261.89107163393;6936.930630693629;6941.965290173517;6945.617991010341;6958.582485050913;6966.651667416711;6721.462283017445;6893.7931862044215;6904.206016308189;6905.447564194953;6905.896411553931;7230.89153662706;7268.745593904188;7269.672864720995;7273.854522909406;7277.3086556777025;4633.189191891473;4668.369770081132;4672.883177920579;4686.4610569783545;4693.037927224979;6835.521513494008;6979.6859141337745;6990.685419156049;6991.580505169706;6997.580145191305;6728.495362847708;6748.69630866622;6770.4922130839395;6773.62745049027;6773.661316934232;6729.495287853332;6731.32686731323;6745.12313399255;6758.797236082925;6769.89845152333;6492.383223593751;6633.568818026813;6636.668166591555;6641.667916604247;6643;6787.524873258824;6791;6796.048553202654;6797.218319893275;6803.93196068033;6630.83422914431;6637.070810086495;6637.70130344126;6643.667816609053;6646.567973081735 18:18:52 INFO - 2015-11-23 18:18:52,171 DEBUG : BROWSER_OUTPUT: |5;yui.html;648.5460177875485;657.1391477164913;683.9042534045233;709.0286307758369;716.3230746944133;13744.106633068855;14182;14199.64500887477;14205.573832785007;14229.359678814453;13134;13271.601851944457;13710.108842925203;13746.312684365792;13792;799.4643588795492;804.8350088231928;810.9270165685087;825.6325934958945;831.629924683516;826.4545400035978;829.050736906242;832.5379414424991;832.8084540555676;834.8497270491309;845.5349557743248;847.2628812932758;850.553459433796;852.8507511185549;856.6359297298654;845.9915400845974;846.3948276981987;846.5132548784474;846.9491830490159;849.4054162086534;616.6916541729136;616.9876602467971;617.9104029915637;619.4672581579838;621.527638994363;12270.509179632892;12477.064220183433;12489.812652810215;12499.312537810416;12514.624561263177;11185.105191584735;11997.160198786089;12025.218360806519;12046.518139274418;12358.876411235953;10278.794424111551;10448.68653940383;10518.894811051869;10533.789324213549;10857.945710271477;3554.6267641897743;3660.377735784923;3676.3198808220563;3684.299983003221;3802.90492737681;48427.515724843004;48703.512964870606;48985.51014489809;49024.264636030486;49108.50891491075;9874.851877221847;9917.851232231378;10239.692809215736;10307.84538231927;10527.263091583516;17778.46664600064;17791.288348465918;17920.551986200448;17956.371526996492;18026.098695065193;7639.427042971799;7688.231176882379;7712.5758083305955;7870.252326029018;8009.719659811879;4280.0369916769105;4452.688311818207;4460.442444694413;4464.196444639996;4478.126765280804;3117.3297740985795;3123.6095488063993;3131.7964332318325;3139.1681204480833;3145.528170774402;553.692700551198;563.2904135947462;567.7814041594016;577.2553406106076;579.7217335678898;614.913912052313;660.2473180574053;662.1855118204539;668.6823758714669;702.3468174597571;8387.859912857351;8662.740117796477;8770.693025744196;8847.16492659777;9047.638094476279;625.8904691678938;645.6674812471581;660.9239937407164;667.2926697700453;670.2761018100538;4211.052513184571;4257.57424257578;4380.3210502372185;4417.469903611523;4507.706999045052;52970.470295297324;53035.20447193295;53140.20289695658;53367.73316133395;53716.46283537193;46263.30605040927;46843.531564683915;48543.5145648539;48763.75618121958;50625.74687126541;47538;47539;47820.76089619599;47925.76037119792;48297.27554086692;8801.955990220007;9098.681546145854;9153.77115572116;9222.40054396462;9240.168384845396;35637.46543801897;36025.81987090153;36388.27223455463;37342.62657373391;37519.62480375161;42869.356959645;43165.7841710802;44658.55341446544;46427.76786116183;46484.7675761619;10986.230963832455;10987.83518247211;11257.774844502901;11408.486618102039;11493.48279327449;11839.1712580122;12029.939850300692;12322.691932701755;12359.196652217577;12812.231266124063;11732.88267117352;11779.764404711686;11942.223755455867;12343.69140771488;12351.814722778985;11776.587819426279;11948.191369866989;12025.398730063636;12356.011519078273;12398.318092504638;11134.3876086816;11362.431878406212;11382.715432114263;11464.25482343645;11811.173217874666;10101.494925253855;10116.342437741523;10238.846417303597;10421.895781042092;10568.418736969541;8917.73246802597;9544.284178686488;9688.321817472992;10106.898931010595;10161.84757228657 18:18:52 INFO - 2015-11-23 18:18:52,171 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:18:52 INFO - 2015-11-23 18:18:52,172 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:18:52 INFO - 2015-11-23 18:18:52,173 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,321 18:18:52 INFO - 2015-11-23 18:18:52,173 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:18:52 INFO - 2015-11-23 18:18:52,173 DEBUG : BROWSER_OUTPUT: __startTimestamp1448331532160__endTimestamp 18:18:52 INFO - 2015-11-23 18:18:52,173 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,174 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:18:52 INFO - 2015-11-23 18:18:52,174 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5493.16 Median:5729.97 stddev:618.29 (10.8%) stddev-sans-first:593.46 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: Values: 3201.0 3291.0 3310.0 3334.5 3336.4 5335.9 5525.2 5554.3 5565.1 5572.9 5333.0 5395.9 5411.8 5458.7 5532.3 4951.2 5037.4 5084.7 5112.6 5113.9 4992.1 5015.4 5049.0 5065.7 5077.9 4913.6 4927.6 5038.0 5040.6 5052.4 5007.7 5010.6 5015.9 5034.4 5077.5 5008.8 5010.3 5012.7 5016.2 5043.6 5081.3 5140.8 5165.9 5235.0 5285.2 5902.5 6020.6 6021.2 6022.5 6030.8 6000.2 6005.0 6012.2 6018.8 6025.0 5413.8 5467.8 5584.2 5591.9 5603.9 6048.0 6051.9 6058.0 6059.8 6106.2 5824.6 5879.8 5893.5 5895.6 5907.8 5801.3 5851.8 5871.9 5949.5 5972.3 5847.9 5856.9 5863.9 5865.8 5953.4 5804.2 5884.4 5884.4 5890.1 5898.9 4399.7 4495.7 4495.9 4538.5 4610.3 4081.6 4085.8 4088.2 4094.2 4125.7 5702.7 5702.7 5706.5 5730.1 5781.5 5808.0 5872.2 5957.4 5981.8 6003.2 5662.9 5702.1 5733.6 5747.0 5749.3 4874.8 5020.4 5066.7 5075.6 5127.3 5991.0 6041.1 6048.4 6099.3 6127.1 5915.4 5923.5 5954.6 5957.8 5961.0 5968.0 6088.5 6138.6 6139.7 6149.9 5730.0 5811.2 5811.5 5844.3 5866.4 5880.2 5977.9 5977.9 5999.0 6003.8 5630.7 5712.0 5765.9 5832.0 5843.6 5808.4 5857.0 5857.0 5881.5 5893.0 5678.7 5682.9 5690.3 5712.9 5737.7 5601.9 5756.1 5764.6 5775.8 5829.3 5565.8 5644.9 5656.6 5664.2 5682.6 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44676.25 Median:20767.95 stddev:70126.67 (337.7%) stddev-sans-first:70245.17 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: Values: 560.4 562.7 578.5 581.1 604.1 39081.2 40187.6 40274.4 40284.2 40332.2 43824.3 44342.8 44359.3 44375.1 44556.6 8704.9 8888.8 8890.1 8899.2 8922.4 8503.4 8626.7 8664.7 8665.5 8681.5 8727.7 8728.1 8795.3 8817.8 8818.3 8731.4 8773.1 8797.8 8810.0 8813.3 8614.1 8769.6 8814.9 8817.3 8825.8 40456.8 40826.0 40947.6 40956.8 40987.2 3266.3 3361.6 3521.7 3548.6 4018.6 29677.7 30203.6 30253.7 30626.1 31027.8 3644.2 3712.0 3739.1 3804.4 3816.9 264895.0 265463.0 265636.0 265735.0 266091.0 51993.0 58029.0 58199.0 58479.0 58756.4 53418.5 70217.6 70955.3 71127.0 71274.3 15800.0 15819.7 15854.4 16007.1 16011.8 12006.6 12137.6 12183.7 12199.2 12229.1 19812.3 20259.3 20340.5 20393.3 20407.3 7745.0 7848.2 7919.5 7949.9 8029.0 909.2 990.5 1133.2 1133.3 1137.0 29190.7 29357.7 29906.3 29972.7 30002.2 1005.7 1021.6 1043.2 1095.9 1117.6 14842.3 14843.5 14875.9 14896.3 15004.2 308604.0 314509.0 315381.0 315469.0 316097.0 53653.5 53689.5 53739.0 53743.5 54064.7 1341.2 1342.6 1346.1 1353.4 1360.6 27166.0 28066.2 28376.4 28839.9 29763.9 45121.5 45418.5 45467.3 45587.0 45630.8 217309.0 218150.0 218450.0 218878.0 219105.0 28952.6 29500.9 29794.7 29895.9 29936.9 40741.6 43922.3 44393.8 44831.6 44849.3 37275.4 37796.8 38113.4 38392.2 38891.0 37891.0 38277.8 38372.2 38675.6 39054.8 36189.5 36935.1 37248.4 37250.6 37337.0 30943.8 33807.7 34127.0 34685.8 34771.5 26041.3 26042.3 26250.1 26381.5 26424.5 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,175 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28340.19 Median:354.36 stddev:88509.51 (24977.4%) stddev-sans-first:88732.85 18:18:52 INFO - 2015-11-23 18:18:52,176 DEBUG : BROWSER_OUTPUT: Values: 353.4 353.4 354.1 354.7 355.7 16375.8 16921.5 17374.0 17939.6 18035.3 10910.9 11649.9 16550.3 16600.3 16606.3 4742.7 4955.9 4974.0 4975.3 4986.1 4840.8 4921.8 4924.2 4924.4 4937.1 4862.0 4939.7 4970.4 5081.9 5190.4 5173.9 5185.9 5209.3 5219.2 5223.3 4993.4 5171.8 5208.7 5229.1 5252.5 11888.8 11919.1 12130.2 12396.9 12429.0 2034.8 2081.9 2113.5 2146.3 2179.7 9162.0 9432.4 9869.0 10504.3 13479.7 1755.9 1827.0 1843.3 1857.9 1862.5 60289.2 63048.1 63310.0 64172.7 65204.3 10138.5 10378.5 10397.2 10408.1 10433.6 13508.5 13530.2 13546.9 13593.0 13621.4 7787.6 7794.8 7801.1 7889.9 7898.0 7497.4 7501.3 7551.8 7610.5 7625.8 4408.9 4437.0 4455.5 4462.4 4479.7 1818.8 1821.8 1830.7 1841.3 1850.9 404.6 425.3 430.6 438.9 952.3 10001.7 10388.6 10589.0 10637.4 10724.0 417.1 424.0 424.7 434.0 435.8 9486.5 9992.7 10028.4 10133.8 10199.8 534293.0 538259.0 538660.0 545654.0 550161.0 73888.3 75844.6 77306.6 77611.0 79202.0 1060.3 1105.8 1114.4 1125.2 1141.1 14495.2 15526.7 15564.3 15630.8 15881.7 34167.5 34418.7 34808.5 35599.5 36162.8 42789.0 44575.1 44691.6 46913.0 48161.3 8590.6 8951.9 9038.1 9058.4 9067.9 10225.4 11681.6 16848.6 17140.1 17527.7 13540.0 13784.4 14026.9 14090.9 14516.6 13686.4 14091.0 14199.0 14461.6 14973.7 14383.2 14979.0 15077.3 15312.2 15822.7 9035.3 9416.8 9424.5 9456.1 9840.1 13952.5 14161.9 14674.7 14728.3 14999.5 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5405.56 Median:6584.74 stddev:2824.26 (42.9%) stddev-sans-first:2816.31 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: Values: 1421.7 1481.9 1606.5 1629.9 1770.9 6824.5 6886.6 7385.9 7493.1 7532.1 7329.6 7477.7 7547.9 7558.7 7579.5 2251.7 2284.2 2293.1 2293.3 2293.4 2221.2 2221.4 2236.3 2251.2 2262.0 2241.0 2260.8 2266.4 2271.4 2302.3 2259.6 2298.0 2337.7 2337.9 2341.7 2313.1 2342.3 2344.3 2344.8 2345.7 7817.8 7845.1 7852.1 7852.4 7872.9 4635.0 4737.3 4742.5 4745.0 4755.1 3134.1 3171.7 3209.7 3246.0 3269.9 984.8 989.3 994.6 1015.1 1035.9 10668.9 10699.5 10705.7 10718.7 10719.6 4242.4 4268.2 4275.9 4294.1 4304.9 9096.9 9117.0 9172.5 9184.6 9190.2 4761.1 4769.2 4785.9 4788.1 4793.5 3858.1 3893.6 4165.4 4276.3 4317.0 3584.1 3609.9 3616.3 3623.1 3673.8 1216.2 1216.9 1218.9 1230.1 1233.6 918.6 940.1 943.4 963.9 1158.6 7264.6 7284.6 7296.5 7300.2 7305.7 468.8 475.9 484.8 490.4 493.7 5725.1 5757.0 5788.8 5789.9 5815.5 9306.8 9318.2 9375.3 9441.6 9483.3 9053.7 9247.5 9343.9 9383.0 9389.1 7145.5 7173.1 7187.7 7281.3 7284.3 6824.8 6889.9 6897.7 6899.8 6907.2 8096.4 8234.6 8313.6 8321.4 8430.0 8340.2 8390.5 8466.3 8578.5 8581.9 6484.4 6823.7 6827.2 6839.0 6936.1 7518.8 7531.1 7545.8 7583.1 7585.4 6958.9 7085.6 7141.3 7244.6 7273.5 6820.6 6823.8 6939.1 6939.1 6944.4 6672.2 6684.6 6996.7 7023.6 7043.3 6297.7 6349.0 6349.3 6354.1 6362.6 6462.4 6584.5 6585.0 6591.4 6608.1 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5591.07 Median:6355.86 stddev:1706.30 (26.8%) stddev-sans-first:1664.62 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: Values: 321.7 328.1 329.5 334.8 339.6 6266.7 6275.7 6287.1 6330.7 6437.7 6202.2 6247.9 6259.9 6261.8 6307.2 4781.2 4781.2 4786.4 4791.1 4794.0 4791.3 4800.6 4806.6 4807.8 4808.0 4629.9 4694.3 4712.5 4719.1 4745.2 4714.7 4729.2 4735.7 4740.0 4742.3 4130.1 4136.5 4139.0 4141.3 4148.8 5898.4 5899.2 5936.8 5939.0 5953.4 5413.5 5553.1 5567.1 5569.6 5585.8 6583.1 6616.1 6628.5 6629.2 6633.4 4592.9 4593.7 4604.0 4607.1 4611.1 6739.6 6849.1 6880.8 6901.3 6912.8 4688.2 4701.5 4702.5 4710.9 4714.8 4772.8 4780.5 4782.0 4782.3 4782.8 5536.0 5549.6 5551.9 5553.7 5555.6 6586.6 6652.4 6667.7 6677.1 6678.3 1656.5 1683.1 1699.5 1700.5 1704.4 1120.0 1125.2 1134.6 1137.8 1146.9 6353.5 6358.2 6370.6 6410.3 6417.7 6726.8 6736.1 6744.2 6764.1 6794.6 6335.8 6387.8 6392.3 6400.7 6416.6 3718.3 3736.5 3740.5 3745.6 3765.0 7130.8 7210.0 7256.7 7266.9 7298.8 7032.2 7185.4 7186.0 7187.0 7220.5 7206.0 7251.8 7253.5 7260.7 7261.9 6936.9 6942.0 6945.6 6958.6 6966.7 6721.5 6893.8 6904.2 6905.4 6905.9 7230.9 7268.7 7269.7 7273.9 7277.3 4633.2 4668.4 4672.9 4686.5 4693.0 6835.5 6979.7 6990.7 6991.6 6997.6 6728.5 6748.7 6770.5 6773.6 6773.7 6729.5 6731.3 6745.1 6758.8 6769.9 6492.4 6633.6 6636.7 6641.7 6643.0 6787.5 6791.0 6796.0 6797.2 6803.9 6630.8 6637.1 6637.7 6643.7 6646.6 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,177 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13800.74 Median:24568.34 stddev:15606.52 (63.5%) stddev-sans-first:15619.05 18:18:52 INFO - 2015-11-23 18:18:52,179 DEBUG : BROWSER_OUTPUT: Values: 648.5 657.1 683.9 709.0 716.3 13744.1 14182.0 14199.6 14205.6 14229.4 13134.0 13271.6 13710.1 13746.3 13792.0 799.5 804.8 810.9 825.6 831.6 826.5 829.1 832.5 832.8 834.8 845.5 847.3 850.6 852.9 856.6 846.0 846.4 846.5 846.9 849.4 616.7 617.0 617.9 619.5 621.5 12270.5 12477.1 12489.8 12499.3 12514.6 11185.1 11997.2 12025.2 12046.5 12358.9 10278.8 10448.7 10518.9 10533.8 10857.9 3554.6 3660.4 3676.3 3684.3 3802.9 48427.5 48703.5 48985.5 49024.3 49108.5 9874.9 9917.9 10239.7 10307.8 10527.3 17778.5 17791.3 17920.6 17956.4 18026.1 7639.4 7688.2 7712.6 7870.3 8009.7 4280.0 4452.7 4460.4 4464.2 4478.1 3117.3 3123.6 3131.8 3139.2 3145.5 553.7 563.3 567.8 577.3 579.7 614.9 660.2 662.2 668.7 702.3 8387.9 8662.7 8770.7 8847.2 9047.6 625.9 645.7 660.9 667.3 670.3 4211.1 4257.6 4380.3 4417.5 4507.7 52970.5 53035.2 53140.2 53367.7 53716.5 46263.3 46843.5 48543.5 48763.8 50625.7 47538.0 47539.0 47820.8 47925.8 48297.3 8802.0 9098.7 9153.8 9222.4 9240.2 35637.5 36025.8 36388.3 37342.6 37519.6 42869.4 43165.8 44658.6 46427.8 46484.8 10986.2 10987.8 11257.8 11408.5 11493.5 11839.2 12029.9 12322.7 12359.2 12812.2 11732.9 11779.8 11942.2 12343.7 12351.8 11776.6 11948.2 12025.4 12356.0 12398.3 11134.4 11362.4 11382.7 11464.3 11811.2 10101.5 10116.3 10238.8 10421.9 10568.4 8917.7 9544.3 9688.3 10106.9 10161.8 18:18:52 INFO - 2015-11-23 18:18:52,179 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:18:52 INFO - 2015-11-23 18:18:52,179 DEBUG : BROWSER_OUTPUT: 18:18:52 INFO - 2015-11-23 18:18:52,841 INFO : Browser exited with error code: 0 18:18:52 INFO - 2015-11-23 18:18:52,856 INFO : Completed test dromaeo_css (00:18:30) 18:18:52 INFO - 2015-11-23 18:18:52,856 INFO : Starting test kraken 18:18:52 INFO - 2015-11-23 18:18:52,856 DEBUG : operating with platform_type : linux_ 18:18:52 INFO - 2015-11-23 18:18:52,856 INFO : Initialising browser for kraken test... 18:18:52 INFO - 2015-11-23 18:18:52,862 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnX95AC/profile http://localhost:44558/getInfo.html 18:18:57 INFO - 2015-11-23 18:18:57,171 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:18:57 INFO - 2015-11-23 18:18:57,171 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:18:57 INFO - 2015-11-23 18:18:57,171 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:18:57 INFO - 2015-11-23 18:18:57,172 DEBUG : BROWSER_OUTPUT: __metrics 18:18:58 INFO - 2015-11-23 18:18:58,181 INFO : Browser initialized. 18:18:58 INFO - 2015-11-23 18:18:58,182 INFO : Running cycle 1/1 for kraken test... 18:18:58 INFO - 2015-11-23 18:18:58,182 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnX95AC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:19:09 INFO - 2015-11-23 18:19:09,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 140091392 18:19:09 INFO - 2015-11-23 18:19:09,967 DEBUG : BROWSER_OUTPUT: 18:19:12 INFO - 2015-11-23 18:19:12,420 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44558/tests/kraken/driver.html?testName=audio-beat-detection) 18:19:13 INFO - 2015-11-23 18:19:13,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 141946880 18:19:13 INFO - 2015-11-23 18:19:13,027 DEBUG : BROWSER_OUTPUT: 18:19:15 INFO - 2015-11-23 18:19:15,676 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44558/tests/kraken/driver.html?testName=audio-dft) 18:19:16 INFO - 2015-11-23 18:19:16,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 141926400 18:19:16 INFO - 2015-11-23 18:19:16,283 DEBUG : BROWSER_OUTPUT: 18:19:19 INFO - 2015-11-23 18:19:19,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44558/tests/kraken/driver.html?testName=audio-fft) 18:19:19 INFO - 2015-11-23 18:19:19,715 DEBUG : BROWSER_OUTPUT: RSS: Main: 137515008 18:19:19 INFO - 2015-11-23 18:19:19,715 DEBUG : BROWSER_OUTPUT: 18:19:21 INFO - 2015-11-23 18:19:21,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44558/tests/kraken/driver.html?testName=audio-oscillator) 18:19:22 INFO - 2015-11-23 18:19:22,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 137248768 18:19:22 INFO - 2015-11-23 18:19:22,578 DEBUG : BROWSER_OUTPUT: 18:19:24 INFO - 2015-11-23 18:19:24,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44558/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:19:25 INFO - 2015-11-23 18:19:25,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 139079680 18:19:25 INFO - 2015-11-23 18:19:25,365 DEBUG : BROWSER_OUTPUT: 18:19:28 INFO - 2015-11-23 18:19:28,378 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44558/tests/kraken/driver.html?testName=imaging-darkroom) 18:19:28 INFO - 2015-11-23 18:19:28,967 DEBUG : BROWSER_OUTPUT: RSS: Main: 139665408 18:19:28 INFO - 2015-11-23 18:19:28,967 DEBUG : BROWSER_OUTPUT: 18:19:32 INFO - 2015-11-23 18:19:32,370 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44558/tests/kraken/driver.html?testName=imaging-desaturate) 18:19:32 INFO - 2015-11-23 18:19:32,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 140218368 18:19:32 INFO - 2015-11-23 18:19:32,957 DEBUG : BROWSER_OUTPUT: 18:19:35 INFO - 2015-11-23 18:19:35,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44558/tests/kraken/driver.html?testName=json-parse-financial) 18:19:36 INFO - 2015-11-23 18:19:36,380 DEBUG : BROWSER_OUTPUT: RSS: Main: 140279808 18:19:36 INFO - 2015-11-23 18:19:36,380 DEBUG : BROWSER_OUTPUT: 18:19:37 INFO - 2015-11-23 18:19:37,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44558/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:19:38 INFO - 2015-11-23 18:19:38,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 139374592 18:19:38 INFO - 2015-11-23 18:19:38,563 DEBUG : BROWSER_OUTPUT: 18:19:40 INFO - 2015-11-23 18:19:40,418 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:19:41 INFO - 2015-11-23 18:19:41,021 DEBUG : BROWSER_OUTPUT: RSS: Main: 139649024 18:19:41 INFO - 2015-11-23 18:19:41,021 DEBUG : BROWSER_OUTPUT: 18:19:43 INFO - 2015-11-23 18:19:43,118 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:19:43 INFO - 2015-11-23 18:19:43,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 140439552 18:19:43 INFO - 2015-11-23 18:19:43,700 DEBUG : BROWSER_OUTPUT: 18:19:46 INFO - 2015-11-23 18:19:46,180 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:19:46 INFO - 2015-11-23 18:19:46,773 DEBUG : BROWSER_OUTPUT: RSS: Main: 140525568 18:19:46 INFO - 2015-11-23 18:19:46,773 DEBUG : BROWSER_OUTPUT: 18:19:49 INFO - 2015-11-23 18:19:49,355 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:19:49 INFO - 2015-11-23 18:19:49,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 139522048 18:19:49 INFO - 2015-11-23 18:19:49,944 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,621 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44558/tests/kraken/driver.html?testName=ai-astar) 18:19:51 INFO - 2015-11-23 18:19:51,871 DEBUG : BROWSER_OUTPUT: RSS: Main: 139870208 18:19:51 INFO - 2015-11-23 18:19:51,871 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,872 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:19:51 INFO - 2015-11-23 18:19:51,873 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:19:51 INFO - 2015-11-23 18:19:51,873 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:19:51 INFO - 2015-11-23 18:19:51,873 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:19:51 INFO - 2015-11-23 18:19:51,873 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;105;113;109;110;122;111;110;112;110 18:19:51 INFO - 2015-11-23 18:19:51,873 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;133;161;171;127;155;149;133;160;125 18:19:51 INFO - 2015-11-23 18:19:51,873 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;162;161;163;161;165;161;164;171;160 18:19:51 INFO - 2015-11-23 18:19:51,874 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;76;72;72;72;138;71;73;73 18:19:51 INFO - 2015-11-23 18:19:51,874 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;144;93;93;94;132;93;94;139 18:19:51 INFO - 2015-11-23 18:19:51,874 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;111;110;111;110;110;110;110;110 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;146;147;146;148;147;146;148;146 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;97;100;97;96;96;96 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;68;66;64;62;65;63;62;63;61;60 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;68;68;68;68;68;68;69;69;69 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;88;93;88;92;84;85;87;84 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;120;131;117;129;117;121;119;127;123 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;144;143;151;148;165;152;150;152;147 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;67;72;66;69;66;65;67;66;69 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,734 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: __startTimestamp1448331591872__endTimestamp 18:19:51 INFO - 2015-11-23 18:19:51,875 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.20 Median:110.50 stddev:4.34 (3.9%) stddev-sans-first:4.58 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: Values: 110.0 105.0 113.0 109.0 110.0 122.0 111.0 110.0 112.0 110.0 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.50 Median:153.00 stddev:15.98 (10.4%) stddev-sans-first:16.87 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: Values: 151.0 133.0 161.0 171.0 127.0 155.0 149.0 133.0 160.0 125.0 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.00 Median:162.50 stddev:3.20 (2.0%) stddev-sans-first:3.37 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: Values: 162.0 162.0 161.0 163.0 161.0 165.0 161.0 164.0 171.0 160.0 18:19:51 INFO - 2015-11-23 18:19:51,879 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.10 Median:72.00 stddev:20.74 (28.8%) stddev-sans-first:21.84 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 76.0 72.0 72.0 72.0 138.0 71.0 73.0 73.0 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.90 Median:93.00 stddev:21.88 (23.5%) stddev-sans-first:22.62 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 144.0 93.0 93.0 94.0 132.0 93.0 94.0 139.0 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 111.0 110.0 111.0 110.0 110.0 110.0 110.0 110.0 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,880 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.83 18:19:51 INFO - 2015-11-23 18:19:51,881 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 146.0 147.0 146.0 148.0 147.0 146.0 148.0 146.0 18:19:51 INFO - 2015-11-23 18:19:51,881 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,881 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.70 Median:96.00 stddev:1.25 (1.3%) stddev-sans-first:1.30 18:19:51 INFO - 2015-11-23 18:19:51,881 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 97.0 100.0 97.0 96.0 96.0 96.0 18:19:51 INFO - 2015-11-23 18:19:51,881 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,882 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.40 Median:63.50 stddev:2.41 (3.8%) stddev-sans-first:1.90 18:19:51 INFO - 2015-11-23 18:19:51,882 DEBUG : BROWSER_OUTPUT: Values: 68.0 66.0 64.0 62.0 65.0 63.0 62.0 63.0 61.0 60.0 18:19:51 INFO - 2015-11-23 18:19:51,882 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,882 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 18:19:51 INFO - 2015-11-23 18:19:51,882 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 68.0 68.0 68.0 68.0 68.0 69.0 69.0 69.0 18:19:51 INFO - 2015-11-23 18:19:51,882 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.50 stddev:3.30 (3.8%) stddev-sans-first:3.32 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 88.0 93.0 88.0 92.0 84.0 85.0 87.0 84.0 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:124.50 stddev:5.01 (4.0%) stddev-sans-first:5.20 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: Values: 126.0 120.0 131.0 117.0 129.0 117.0 121.0 119.0 127.0 123.0 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.10 Median:150.50 stddev:7.03 (4.7%) stddev-sans-first:6.44 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: Values: 139.0 144.0 143.0 151.0 148.0 165.0 152.0 150.0 152.0 147.0 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:2.07 (3.1%) stddev-sans-first:2.19 18:19:51 INFO - 2015-11-23 18:19:51,883 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 72.0 66.0 69.0 66.0 65.0 67.0 66.0 69.0 18:19:51 INFO - 2015-11-23 18:19:51,884 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:19:51 INFO - 2015-11-23 18:19:51,884 DEBUG : BROWSER_OUTPUT: 18:19:52 INFO - 2015-11-23 18:19:52,753 INFO : Browser exited with error code: 0 18:19:52 INFO - 2015-11-23 18:19:52,801 INFO : Completed test kraken (00:00:59) 18:19:52 INFO - 2015-11-23 18:19:52,802 INFO : Starting test v8_7 18:19:52 INFO - 2015-11-23 18:19:52,802 DEBUG : operating with platform_type : linux_ 18:19:52 INFO - 2015-11-23 18:19:52,802 INFO : Initialising browser for v8_7 test... 18:19:52 INFO - 2015-11-23 18:19:52,808 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplCEWay/profile http://localhost:44558/getInfo.html 18:19:57 INFO - 2015-11-23 18:19:57,180 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:19:57 INFO - 2015-11-23 18:19:57,180 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:19:57 INFO - 2015-11-23 18:19:57,180 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:19:57 INFO - 2015-11-23 18:19:57,180 DEBUG : BROWSER_OUTPUT: __metrics 18:19:58 INFO - 2015-11-23 18:19:58,221 INFO : Browser initialized. 18:19:58 INFO - 2015-11-23 18:19:58,222 INFO : Running cycle 1/1 for v8_7 test... 18:19:58 INFO - 2015-11-23 18:19:58,222 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplCEWay/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:20:10 INFO - 2015-11-23 18:20:10,045 DEBUG : BROWSER_OUTPUT: RSS: Main: 140156928 18:20:10 INFO - 2015-11-23 18:20:10,045 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,418 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44558/tests/v8_7/run.html (next: http://localhost:44558/tests/v8_7/run.html) 18:20:31 INFO - 2015-11-23 18:20:31,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 138878976 18:20:31 INFO - 2015-11-23 18:20:31,668 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,669 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: |0;Richards;184.26386585590566 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.30769230769232 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.7684449489217 18:20:31 INFO - 2015-11-23 18:20:31,670 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5015 18:20:31 INFO - 2015-11-23 18:20:31,671 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1060.4453870625662 18:20:31 INFO - 2015-11-23 18:20:31,671 DEBUG : BROWSER_OUTPUT: |5;Earley;696.8641114982578 18:20:31 INFO - 2015-11-23 18:20:31,671 DEBUG : BROWSER_OUTPUT: |6;Boyer;12292.682926829268 18:20:31 INFO - 2015-11-23 18:20:31,671 DEBUG : BROWSER_OUTPUT: |7;RegExp;26921.052631578947 18:20:31 INFO - 2015-11-23 18:20:31,671 DEBUG : BROWSER_OUTPUT: |8;Splay;703.004891684137 18:20:31 INFO - 2015-11-23 18:20:31,672 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4859.223300970873 18:20:31 INFO - 2015-11-23 18:20:31,672 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:20:31 INFO - 2015-11-23 18:20:31,672 DEBUG : BROWSER_OUTPUT: __startTimestamp1448331631668__endTimestamp 18:20:31 INFO - 2015-11-23 18:20:31,672 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,672 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:20:31 INFO - 2015-11-23 18:20:31,673 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:20:31 INFO - 2015-11-23 18:20:31,673 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,673 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:184.26 Median:184.26 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,673 DEBUG : BROWSER_OUTPUT: Values: 184.3 18:20:31 INFO - 2015-11-23 18:20:31,673 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,673 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.31 Median:192.31 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,674 DEBUG : BROWSER_OUTPUT: Values: 192.3 18:20:31 INFO - 2015-11-23 18:20:31,674 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,674 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.77 Median:283.77 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,674 DEBUG : BROWSER_OUTPUT: Values: 283.8 18:20:31 INFO - 2015-11-23 18:20:31,675 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,675 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5015.00 Median:5015.00 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,675 DEBUG : BROWSER_OUTPUT: Values: 5015.0 18:20:31 INFO - 2015-11-23 18:20:31,675 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,675 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1060.45 Median:1060.45 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,675 DEBUG : BROWSER_OUTPUT: Values: 1060.4 18:20:31 INFO - 2015-11-23 18:20:31,676 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,676 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:696.86 Median:696.86 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,676 DEBUG : BROWSER_OUTPUT: Values: 696.9 18:20:31 INFO - 2015-11-23 18:20:31,676 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,676 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12292.68 Median:12292.68 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,677 DEBUG : BROWSER_OUTPUT: Values: 12292.7 18:20:31 INFO - 2015-11-23 18:20:31,677 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,678 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26921.05 Median:26921.05 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,678 DEBUG : BROWSER_OUTPUT: Values: 26921.1 18:20:31 INFO - 2015-11-23 18:20:31,678 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,678 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:703.00 Median:703.00 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,679 DEBUG : BROWSER_OUTPUT: Values: 703.0 18:20:31 INFO - 2015-11-23 18:20:31,679 DEBUG : BROWSER_OUTPUT: 18:20:31 INFO - 2015-11-23 18:20:31,679 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4859.22 Median:4859.22 stddev:0.00 (0.0%) 18:20:31 INFO - 2015-11-23 18:20:31,679 DEBUG : BROWSER_OUTPUT: Values: 4859.2 18:20:31 INFO - 2015-11-23 18:20:31,679 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:20:31 INFO - 2015-11-23 18:20:31,679 DEBUG : BROWSER_OUTPUT: 18:20:32 INFO - 2015-11-23 18:20:32,673 INFO : Browser exited with error code: 0 18:20:32 INFO - 2015-11-23 18:20:32,688 INFO : Completed test v8_7 (00:00:39) 18:20:32 INFO - 2015-11-23 18:20:32,708 INFO : Completed test suite (00:20:10) 18:20:32 INFO - 2015-11-23 18:20:32,708 DEBUG : Working with test: dromaeo_css 18:20:32 INFO - 2015-11-23 18:20:32,708 DEBUG : Generating results file: dromaeo_css 18:20:32 INFO - 2015-11-23 18:20:32,709 DEBUG : Working with test: kraken 18:20:32 INFO - 2015-11-23 18:20:32,709 DEBUG : Generating results file: kraken 18:20:32 INFO - 2015-11-23 18:20:32,709 INFO : javascript benchmark 18:20:32 INFO - 2015-11-23 18:20:32,710 DEBUG : Working with test: v8_7 18:20:32 INFO - 2015-11-23 18:20:32,710 DEBUG : Generating results file: v8_7 18:20:32 INFO - 2015-11-23 18:20:32,710 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:20:32 INFO - 2015-11-23 18:20:32,767 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 18:20:32 INFO - 2015-11-23 18:20:32,767 DEBUG : process_Request line: dromaeo_css 6989.75 graph.html#tests=[[72,1,43]] 18:20:32 INFO - 2015-11-23 18:20:32,767 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:20:32 INFO - 2015-11-23 18:20:32,807 DEBUG : process_Request line: kraken 1518.80 graph.html#tests=[[232,1,43]] 18:20:32 INFO - 2015-11-23 18:20:32,807 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:20:32 INFO - 2015-11-23 18:20:32,913 DEBUG : process_Request line: v8_7 20752.76 graph.html#tests=[[230,1,43]] 18:20:32 INFO - 2015-11-23 18:20:32,914 INFO : javascript benchmark 18:20:32 INFO - 2015-11-23 18:20:32,918 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3200.9679903200968, 3290.9999999999986, 3309.9573634305175, 3334.549835772171, 3336.432806422908, 5335.946640533593, 5525.171224316355, 5554.250176226204, 5565.053940830068, 5572.8606784830345, 5333.0, 5395.946040539593, 5411.756470958816, 5458.699771512565, 5532.2531458253125, 4951.1830547959635, 5037.370328708911, 5084.745762711849, 5112.642115051948, 5113.923291150636, 4992.126377883874, 5015.448300686921, 5049.040682270357, 5065.721385323805, 5077.933633936877, 4913.631477639191, 4927.6304277179, 5037.967216720582, 5040.647154699154, 5052.368453943257, 5007.724575148365, 5010.574101201402, 5015.899682006376, 5034.446210916796, 5077.517635824591, 5008.7996480140755, 5010.348654674905, 5012.674176178537, 5016.222485514714, 5043.621728370387, 5081.28861959326, 5140.820071297487, 5165.896682066337, 5235.0, 5285.207218917155, 5902.498287645555, 6020.608660437101, 6021.2473440819895, 6022.5483088768515, 6030.758769649209, 6000.219971403733, 6004.969975150097, 6012.158297838306, 6018.759249630009, 6024.969875150597, 5413.783448662049, 5467.781288748485, 5584.190292407578, 5591.916121258185, 5603.915941260884, 6048.0, 6051.880402125621, 6057.969710151421, 6059.8485037873525, 6106.236720409949, 5824.563157763185, 5879.794207202813, 5893.528517718572, 5895.55783316253, 5907.822765317047, 5801.3038435388025, 5851.824445266649, 5871.94128058714, 5949.494292985059, 5972.343042265347, 5847.853803654943, 5856.912146317809, 5863.941360586425, 5865.824025279248, 5953.434423729739, 5804.158397032406, 5884.352721200665, 5884.440978107073, 5890.057590785454, 5898.941010589925, 4399.6920215584605, 4495.730256184574, 4495.88760280989, 4538.52345503724, 4610.308453731967, 4081.612246836605, 4085.816138273785, 4088.202800453883, 4094.160697057096, 4125.690573207022, 5702.686352250583, 5702.686352250583, 5706.457886500776, 5730.140478928194, 5781.4507621775865, 5808.041673123972, 5872.236609240771, 5957.404259574095, 5981.820545383645, 6003.159557661931, 5662.915056274159, 5702.0591602385975, 5733.627314224562, 5747.0, 5749.310082790092, 4874.805007799649, 5020.397552293748, 5066.721330326794, 5075.619328550373, 5127.333446651986, 5991.0, 6041.063635136561, 6048.42539958712, 6099.2985806632805, 6127.080937859357, 5915.438033386821, 5923.49650279731, 5954.642721436728, 5957.821265362046, 5961.016432288624, 5967.9701601491715, 6088.512918966383, 6138.570300078952, 6139.693015349304, 6149.907751383822, 5729.971350143389, 5811.244538210006, 5811.476967072984, 5844.298684157929, 5866.442687944638, 5880.206172166702, 5977.850553736192, 5977.9103313451205, 5998.970005149947, 6003.759849605967, 5630.690312032878, 5712.028955077563, 5765.884682306414, 5832.0, 5843.590948633555, 5808.448197421239, 5856.970715146397, 5857.0, 5881.500072493886, 5892.970535147469, 5678.659280443187, 5682.857928551819, 5690.345610254708, 5712.942870571241, 5737.741801618854, 5601.915971260353, 5756.0502517084215, 5764.59647824665, 5775.797847075332, 5829.271341082364, 5565.805196818092, 5644.887102258014, 5656.604037717485, 5664.150377443417, 5682.6306290091015], "unit": "ms", "name": "dojo.html", "value": 5452.592281900911}, {"lowerIsBetter": false, "replicates": [560.4451592923004, 562.7495764384851, 578.5400606517818, 581.1254062635733, 604.1029071828332, 39081.2183756325, 40187.598124018754, 40274.39588406176, 40284.19431611366, 40332.19335613286, 43824.342634860426, 44342.7782861086, 44359.33460998088, 44375.11249775002, 44556.55443445565, 8704.912950870505, 8888.777780555472, 8890.110988901091, 8899.154580314893, 8922.420042697204, 8503.362247831406, 8626.698065567707, 8664.696735614254, 8665.523396213206, 8681.522516261603, 8727.738167854974, 8728.127187281252, 8795.296376289882, 8817.823643527126, 8818.29453643707, 8731.388802783807, 8773.078826723166, 8797.824043519095, 8810.0, 8813.294936405073, 8614.095519970375, 8769.561521923943, 8814.911850881474, 8817.338699597492, 8825.779355516099, 40456.797716011526, 40826.0, 40947.59052409497, 40956.795216024024, 40987.180256394706, 3266.3467306538787, 3361.647027062172, 3521.6654417830264, 3548.5741710994844, 4018.6383225509553, 29677.70322296771, 30203.583713302884, 30253.697463025528, 30626.081217563507, 31027.84486077578, 3644.2164934538937, 3711.981440092782, 3739.083924438503, 3804.423702108144, 3816.9045773855596, 264895.0, 265463.0, 265636.0, 265735.0, 266091.0, 51993.0, 58029.0, 58199.0, 58479.0, 58756.41243587509, 53418.465815342126, 70217.64891175511, 70955.29044709487, 71127.0, 71274.2872571278, 15800.0, 15819.68360632781, 15854.365825366856, 16007.119608421648, 16011.759823602655, 12006.63980080599, 12137.575184868487, 12183.695407614881, 12199.207051541622, 12229.143959922898, 19812.306569270007, 20259.29092481756, 20340.491487712927, 20393.286234981704, 20407.285744998855, 7744.961275193701, 7848.215178482106, 7919.524828510308, 7949.920500794918, 8029.0, 909.2226146644656, 990.5294984882267, 1133.18410744264, 1133.3333333333333, 1136.9545218191345, 29190.708092919223, 29357.706422935924, 29906.252343691584, 29972.700272997427, 30002.24994375158, 1005.6982905128432, 1021.6066814276558, 1043.232339213671, 1095.8520599719095, 1117.6088369070924, 14842.332095055532, 14843.480478183212, 14875.925620372045, 14896.329665165094, 15004.174770387515, 308604.0, 314509.0, 315381.0, 315469.0, 316097.0, 53653.463465365625, 53689.46310536845, 53739.0, 53743.46256537385, 54064.729676351366, 1341.161773891318, 1342.637487878287, 1346.0577595683062, 1353.3503918119359, 1360.6190266725332, 27166.0, 28066.158015259574, 28376.43247135087, 28839.855800720863, 29763.851180743957, 45121.54878451173, 45418.545814542755, 45467.31799023083, 45587.0, 45630.771846141884, 217309.0, 218150.0, 218450.0, 218878.0, 219105.0, 28952.565711514766, 29500.852495737385, 29794.70205298006, 29895.850520747255, 29936.850315749154, 40741.59258407378, 43922.34116488319, 44393.77803111093, 44831.551684484046, 44849.327260090475, 37275.39320192563, 37796.811015944746, 38113.428298576095, 38392.2321553573, 38891.0, 37891.0, 38277.80861095677, 38372.23255534929, 38675.6132438672, 39054.80472597619, 36189.457158143174, 36935.076623083565, 37248.44127338146, 37250.627493724714, 37337.0, 30943.845280774352, 33807.66192338045, 34127.0, 34685.826570866986, 34771.478427824106, 26041.348966275993, 26042.34894127586, 26250.13699449027, 26381.472370552867, 26424.471510569296], "unit": "ms", "name": "ext.html", "value": 18640.289279356446}, {"lowerIsBetter": false, "replicates": [353.3798184186752, 353.38863765685346, 354.06173639854387, 354.65421214316063, 355.67278104144185, 16375.754363684538, 16921.492355229348, 17374.0, 17939.551511212227, 18035.27858885643, 10910.890891091087, 11649.883501164986, 16550.337986480557, 16600.25298861551, 16606.335746570152, 4742.691725037878, 4955.900881982358, 4973.975130124344, 4975.253711943201, 4986.127427700156, 4840.806367745304, 4921.753912304371, 4924.236743304794, 4924.433690125627, 4937.136001199776, 4862.0, 4939.654224204307, 4970.353853998975, 5081.923771143417, 5190.351206099238, 5173.948260517422, 5185.87035324118, 5209.348831396075, 5219.164933610623, 5223.320968274138, 4993.400791904995, 5171.793128274864, 5208.66143700662, 5229.084910140748, 5252.501012403815, 11888.821667674993, 11919.106067044919, 12130.211536250115, 12396.876031239663, 12429.0, 2034.7558293004934, 2081.9271325503537, 2113.50332671822, 2146.302451703187, 2179.716636837201, 9161.954190229073, 9432.434053956784, 9869.0, 10504.2647014709, 13479.663008424868, 1755.8507526860105, 1826.963460730778, 1843.317972350239, 1857.860660450458, 1862.4691962790675, 60289.22133868455, 63048.054279185846, 63310.0, 64172.679136604485, 65204.3479565203, 10138.543765530569, 10378.532966016397, 10397.220208484394, 10408.115310198567, 10433.5826566938, 13508.459661613426, 13530.18818870871, 13546.864531354757, 13593.0, 13621.387037583332, 7787.5716835574585, 7794.805129871798, 7801.141874393812, 7889.921100788919, 7898.0, 7497.400207983348, 7501.324880760758, 7551.773446796605, 7610.505317154367, 7625.809354766176, 4408.867733967986, 4436.977815110969, 4455.465344158657, 4462.44219472566, 4479.686421950432, 1818.7999320074782, 1821.799602043774, 1830.6704793137362, 1841.3371186372894, 1850.8796928199893, 404.58530006742734, 425.3237352609304, 430.55007517144657, 438.9451318585177, 952.2524818017823, 10001.749956251151, 10388.636397726043, 10589.0, 10637.414942178255, 10724.0, 417.1219582778208, 423.9766812825263, 424.67936707785424, 433.98698039058877, 435.8104224662282, 9486.478243696663, 9992.700218993441, 10028.448435336126, 10133.797324053477, 10199.796004080024, 534293.0, 538259.0, 538660.0, 545654.0, 550161.0, 73888.26111738921, 75844.62077689577, 77306.61346693231, 77611.0, 79202.0, 1060.2790102730216, 1105.7622611138643, 1114.4483480676954, 1125.2179735084037, 1141.0529260713422, 14495.20276384809, 15526.689466210386, 15564.29960651796, 15630.765538516704, 15881.68236635284, 34167.4874876882, 34418.655813441546, 34808.47787283142, 35599.46600801042, 36162.8191859039, 42789.0, 44575.10849783051, 44691.55308446874, 46913.0, 48161.27758083701, 8590.613422395882, 8951.865722014045, 9038.096190381173, 9058.36591438593, 9067.90932090697, 10225.43760093202, 11681.649550513499, 16848.57878553046, 17140.142992850557, 17527.737083943495, 13540.0, 13784.379702913193, 14026.859731402963, 14090.929545352208, 14516.564503064925, 13686.384112714753, 14091.013629045869, 14199.0, 14461.638459038608, 14973.700525989638, 14383.208923509308, 14979.0, 15077.321520531383, 15312.234388280764, 15822.68354632924, 9035.277177825626, 9416.81166376655, 9424.481653509123, 9456.101670341306, 9840.065193806864, 13952.511662091778, 14161.91562143647, 14674.70650586961, 14728.263586820402, 14999.475018374742], "unit": "ms", "name": "jquery.html", "value": 8228.019136736524}, {"lowerIsBetter": false, "replicates": [1421.651695334643, 1481.881449484041, 1606.5260748079315, 1629.8859079864415, 1770.8671849611274, 6824.454043676506, 6886.586804791716, 7385.852282954338, 7493.138289096755, 7532.096148462186, 7329.56022638642, 7477.73827916023, 7547.886781698266, 7558.735444259452, 7579.4694371394025, 2251.6735073414384, 2284.2233640562204, 2293.0598454633605, 2293.3234695764704, 2293.3807871874583, 2221.1892659179443, 2221.378014156031, 2236.329101269621, 2251.2008237075847, 2262.049939025605, 2241.0, 2260.8078313343385, 2266.410733209369, 2271.3980795089315, 2302.286291249718, 2259.5819773341987, 2297.9770202298014, 2337.672725818387, 2337.859728416292, 2341.7072865891764, 2313.109452860644, 2342.3090188394463, 2344.284993077111, 2344.765523447659, 2345.7185137783576, 7817.804554886174, 7845.097813751435, 7852.057753069668, 7852.411069169778, 7872.921270787276, 4635.049814787961, 4737.336772851769, 4742.478327383985, 4744.952550474485, 4755.096531659008, 3134.106779567835, 3171.714545690899, 3209.7111259986714, 3246.0, 3269.9182520436943, 984.7931934293807, 989.3470309595632, 994.5723338964242, 1015.0914931136665, 1035.891231420705, 10668.94665526675, 10699.518521666465, 10705.732356691067, 10718.732031699192, 10719.571217151304, 4242.363645453207, 4268.189044081614, 4275.850345237902, 4294.098239369768, 4304.892377690583, 9096.90903090974, 9116.954415228014, 9172.54137293146, 9184.63261469547, 9190.172884440433, 4761.119192949315, 4769.2130798417875, 4785.856424307276, 4788.11419887322, 4793.472718001017, 3858.093348063203, 3893.552241492208, 4165.437665915063, 4276.31578947367, 4317.0, 3584.0502266899293, 3609.9458508122398, 3616.349057169735, 3623.05800491874, 3673.7979411132646, 1216.2216181643755, 1216.9391530423443, 1218.8842060004285, 1230.0712961714028, 1233.6052463211686, 918.6279556779515, 940.149165005671, 943.4433684126399, 963.9228861691048, 1158.5829101523445, 7264.564126152448, 7284.635768211569, 7296.525725827804, 7300.16048154469, 7305.744298949511, 468.82419092840183, 475.8596214116844, 484.7915396379591, 490.43844797706424, 493.7013107070243, 5725.055365864669, 5757.0, 5788.797392091341, 5789.942100579025, 5815.476607105296, 9306.813863722688, 9318.207952323994, 9375.296852736072, 9441.575129119068, 9483.288753343526, 9053.68312109073, 9247.537623118817, 9343.859842102374, 9382.95308523453, 9389.10803473669, 7145.464090193256, 7173.1392232932385, 7187.712491500386, 7281.271872812782, 7284.271572842674, 6824.771541122447, 6889.931100689129, 6897.689603967733, 6899.758043552209, 6907.171139463297, 8096.352291816523, 8234.629441675019, 8313.584320784057, 8321.417500774887, 8430.0, 8340.207680270365, 8390.496570205807, 8466.280366168945, 8578.528180949857, 8581.927259092614, 6484.383983521558, 6823.658817059028, 6827.249002609609, 6838.965805170942, 6936.0636314098965, 7518.812029699301, 7531.133919599097, 7545.773626791205, 7583.05211848519, 7585.393168546393, 6958.930410696031, 7085.645717713991, 7141.250168732416, 7244.601546914971, 7273.490855640054, 6820.556663816836, 6823.829404264933, 6939.063226464363, 6939.097917270924, 6944.4097251734165, 6672.165979252593, 6684.63234522106, 6996.720131194696, 7023.578585284899, 7043.2604576518725, 6297.653629050446, 6348.968255158695, 6349.301576826453, 6354.142190804182, 6362.618242905441, 6462.3537646235, 6584.4732421405215, 6585.0, 6591.373819487141, 6608.140941677551], "unit": "ms", "name": "mootools.html", "value": 4357.464740386386}, {"lowerIsBetter": false, "replicates": [321.72171072022707, 328.0535654636374, 329.5435821387378, 334.75228331035026, 339.5500961226374, 6266.686665666715, 6275.6548389838545, 6287.119803227546, 6330.65181415022, 6437.6781160942, 6202.162708034414, 6247.9062814057825, 6259.906101408483, 6261.843453913654, 6307.211598550181, 4781.187198176301, 4781.235002399617, 4786.449558300805, 4791.089692958344, 4793.952060479385, 4791.257355109946, 4800.639952003597, 4806.615470762331, 4807.78364973577, 4808.0, 4629.907401851961, 4694.319323698061, 4712.481627021025, 4719.056188762244, 4745.21703918853, 4714.693544919586, 4729.2433210686295, 4735.668503204777, 4739.976300118512, 4742.264948932903, 4130.112025914443, 4136.482939632546, 4139.048018955657, 4141.316682747343, 4148.792560372, 5898.41015898411, 5899.203607513016, 5936.821895343147, 5939.0, 5953.404659534055, 5413.512783849433, 5553.111502159675, 5567.081431563827, 5569.554435645138, 5585.776568937237, 6583.144191255106, 6616.073749675002, 6628.502862285348, 6629.237637671633, 6633.369829866155, 4592.862214133548, 4593.655475839326, 4603.987122832971, 4607.147677679606, 4611.100835337111, 6739.59562426256, 6849.1096157499705, 6880.759173428906, 6901.275366086539, 6912.758053468155, 4688.203005489075, 4701.529847015339, 4702.506236845082, 4710.905781884411, 4714.787834547454, 4772.785224664898, 4780.521947805191, 4782.043591281688, 4782.254477115827, 4782.760861956958, 5536.031194541019, 5549.611527193058, 5551.944480555143, 5553.694546799884, 5555.583331250173, 6586.637734924625, 6652.434543063894, 6667.733290668319, 6677.065210870482, 6678.298778628271, 1656.4782093640465, 1683.099541745185, 1699.53262852714, 1700.4728534154203, 1704.3523461084708, 1119.9832002519968, 1125.2123513540555, 1134.5688638317386, 1137.766757814646, 1146.856642919635, 6353.491720662336, 6358.237011558643, 6370.649614271167, 6410.262819775692, 6417.711202995783, 6726.831829204309, 6736.05695202672, 6744.224414192333, 6764.086848275519, 6794.626295553792, 6335.809925702236, 6387.808365749035, 6392.328805475451, 6400.679966001681, 6416.647084410309, 3718.330700473941, 3736.5142531470733, 3740.532433445819, 3745.606711295329, 3764.981175094107, 7130.821729456805, 7210.0, 7256.6734496947765, 7266.927330726625, 7298.817529561803, 7032.226455089936, 7185.389241914496, 7185.993960845486, 7186.964065179641, 7220.4584656151, 7205.963970180221, 7251.8187045324285, 7253.528520646141, 7260.673269702982, 7261.89107163393, 6936.930630693629, 6941.965290173517, 6945.617991010341, 6958.582485050913, 6966.651667416711, 6721.462283017445, 6893.7931862044215, 6904.206016308189, 6905.447564194953, 6905.896411553931, 7230.89153662706, 7268.745593904188, 7269.672864720995, 7273.854522909406, 7277.3086556777025, 4633.189191891473, 4668.369770081132, 4672.883177920579, 4686.4610569783545, 4693.037927224979, 6835.521513494008, 6979.6859141337745, 6990.685419156049, 6991.580505169706, 6997.580145191305, 6728.495362847708, 6748.69630866622, 6770.4922130839395, 6773.62745049027, 6773.661316934232, 6729.495287853332, 6731.32686731323, 6745.12313399255, 6758.797236082925, 6769.89845152333, 6492.383223593751, 6633.568818026813, 6636.668166591555, 6641.667916604247, 6643.0, 6787.524873258824, 6791.0, 6796.048553202654, 6797.218319893275, 6803.93196068033, 6630.83422914431, 6637.070810086495, 6637.70130344126, 6643.667816609053, 6646.567973081735], "unit": "ms", "name": "prototype.html", "value": 5028.759435869792}, {"lowerIsBetter": false, "replicates": [648.5460177875485, 657.1391477164913, 683.9042534045233, 709.0286307758369, 716.3230746944133, 13744.106633068855, 14182.0, 14199.64500887477, 14205.573832785007, 14229.359678814453, 13134.0, 13271.601851944457, 13710.108842925203, 13746.312684365792, 13792.0, 799.4643588795492, 804.8350088231928, 810.9270165685087, 825.6325934958945, 831.629924683516, 826.4545400035978, 829.050736906242, 832.5379414424991, 832.8084540555676, 834.8497270491309, 845.5349557743248, 847.2628812932758, 850.553459433796, 852.8507511185549, 856.6359297298654, 845.9915400845974, 846.3948276981987, 846.5132548784474, 846.9491830490159, 849.4054162086534, 616.6916541729136, 616.9876602467971, 617.9104029915637, 619.4672581579838, 621.527638994363, 12270.509179632892, 12477.064220183433, 12489.812652810215, 12499.312537810416, 12514.624561263177, 11185.105191584735, 11997.160198786089, 12025.218360806519, 12046.518139274418, 12358.876411235953, 10278.794424111551, 10448.68653940383, 10518.894811051869, 10533.789324213549, 10857.945710271477, 3554.6267641897743, 3660.377735784923, 3676.3198808220563, 3684.299983003221, 3802.90492737681, 48427.515724843004, 48703.512964870606, 48985.51014489809, 49024.264636030486, 49108.50891491075, 9874.851877221847, 9917.851232231378, 10239.692809215736, 10307.84538231927, 10527.263091583516, 17778.46664600064, 17791.288348465918, 17920.551986200448, 17956.371526996492, 18026.098695065193, 7639.427042971799, 7688.231176882379, 7712.5758083305955, 7870.252326029018, 8009.719659811879, 4280.0369916769105, 4452.688311818207, 4460.442444694413, 4464.196444639996, 4478.126765280804, 3117.3297740985795, 3123.6095488063993, 3131.7964332318325, 3139.1681204480833, 3145.528170774402, 553.692700551198, 563.2904135947462, 567.7814041594016, 577.2553406106076, 579.7217335678898, 614.913912052313, 660.2473180574053, 662.1855118204539, 668.6823758714669, 702.3468174597571, 8387.859912857351, 8662.740117796477, 8770.693025744196, 8847.16492659777, 9047.638094476279, 625.8904691678938, 645.6674812471581, 660.9239937407164, 667.2926697700453, 670.2761018100538, 4211.052513184571, 4257.57424257578, 4380.3210502372185, 4417.469903611523, 4507.706999045052, 52970.470295297324, 53035.20447193295, 53140.20289695658, 53367.73316133395, 53716.46283537193, 46263.30605040927, 46843.531564683915, 48543.5145648539, 48763.75618121958, 50625.74687126541, 47538.0, 47539.0, 47820.76089619599, 47925.76037119792, 48297.27554086692, 8801.955990220007, 9098.681546145854, 9153.77115572116, 9222.40054396462, 9240.168384845396, 35637.46543801897, 36025.81987090153, 36388.27223455463, 37342.62657373391, 37519.62480375161, 42869.356959645, 43165.7841710802, 44658.55341446544, 46427.76786116183, 46484.7675761619, 10986.230963832455, 10987.83518247211, 11257.774844502901, 11408.486618102039, 11493.48279327449, 11839.1712580122, 12029.939850300692, 12322.691932701755, 12359.196652217577, 12812.231266124063, 11732.88267117352, 11779.764404711686, 11942.223755455867, 12343.69140771488, 12351.814722778985, 11776.587819426279, 11948.191369866989, 12025.398730063636, 12356.011519078273, 12398.318092504638, 11134.3876086816, 11362.431878406212, 11382.715432114263, 11464.25482343645, 11811.173217874666, 10101.494925253855, 10116.342437741523, 10238.846417303597, 10421.895781042092, 10568.418736969541, 8917.73246802597, 9544.284178686488, 9688.321817472992, 10106.898931010595, 10161.84757228657], "unit": "ms", "name": "yui.html", "value": 6363.380551408936}], "name": "dromaeo_css", "value": 6989.7525415102455}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 105.0, 113.0, 109.0, 110.0, 122.0, 111.0, 110.0, 112.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [151.0, 133.0, 161.0, 171.0, 127.0, 155.0, 149.0, 133.0, 160.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.5}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 161.0, 163.0, 161.0, 165.0, 161.0, 164.0, 171.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 163.0}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 76.0, 72.0, 72.0, 72.0, 138.0, 71.0, 73.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 79.1}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 144.0, 93.0, 93.0, 94.0, 132.0, 93.0, 94.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 106.9}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 111.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 146.0, 147.0, 146.0, 148.0, 147.0, 146.0, 148.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 100.0, 97.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.7}, {"lowerIsBetter": true, "replicates": [68.0, 66.0, 64.0, 62.0, 65.0, 63.0, 62.0, 63.0, 61.0, 60.0], "unit": "ms", "name": "json-parse-financial", "value": 63.4}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 69.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 88.0, 93.0, 88.0, 92.0, 84.0, 85.0, 87.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.0}, {"lowerIsBetter": true, "replicates": [126.0, 120.0, 131.0, 117.0, 129.0, 117.0, 121.0, 119.0, 127.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.0}, {"lowerIsBetter": true, "replicates": [139.0, 144.0, 143.0, 151.0, 148.0, 165.0, 152.0, 150.0, 152.0, 147.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.1}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 72.0, 66.0, 69.0, 66.0, 65.0, 67.0, 66.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.5}], "name": "kraken", "value": 1518.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [184.26386585590566], "unit": "score", "name": "Richards", "value": 191.583954}, {"lowerIsBetter": false, "replicates": [192.30769230769232], "unit": "score", "name": "DeltaBlue", "value": 343.8135999999999}, {"lowerIsBetter": false, "replicates": [283.7684449489217], "unit": "score", "name": "Encrypt", "value": 938.0218440000002}, {"lowerIsBetter": false, "replicates": [5015.0], "unit": "score", "name": "Decrypt", "value": 53.07696909272185}, {"lowerIsBetter": false, "replicates": [1060.4453870625662], "unit": "score", "name": "RayTrace", "value": 697.809627}, {"lowerIsBetter": false, "replicates": [696.8641114982578], "unit": "score", "name": "Earley", "value": 956.374405}, {"lowerIsBetter": false, "replicates": [12292.682926829268], "unit": "score", "name": "Boyer", "value": 54.21623611111113}, {"lowerIsBetter": false, "replicates": [26921.052631578947], "unit": "score", "name": "RegExp", "value": 33.83913000977518}, {"lowerIsBetter": false, "replicates": [703.004891684137], "unit": "score", "name": "Splay", "value": 115.91811232604375}, {"lowerIsBetter": false, "replicates": [4859.223300970873], "unit": "score", "name": "NavierStokes", "value": 305.39860139860156}], "name": "v8_7", "value": 20752.76211618938}]} 18:20:32 INFO - RETURN: dromaeo_css: 6989.75 18:20:32 INFO - RETURN: kraken: 1518.80 18:20:32 INFO - RETURN: v8_7: 20752.76 18:20:32 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "20752.76"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1518.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6989.75"}}} 18:20:32 INFO - Return code: 0 18:20:32 INFO - # TBPL SUCCESS # 18:20:32 INFO - Running post-action listener: _resource_record_post_action 18:20:32 INFO - Running post-run listener: _resource_record_post_run 18:20:34 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 36864; Write bytes: 275996672; Read time: 84; Write time: 137344 18:20:34 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 499712; Read time: 0; Write time: 48 18:20:34 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 36864; Write bytes: 275496960; Read time: 84; Write time: 137296 18:20:34 INFO - Running post-run listener: _upload_blobber_files 18:20:34 INFO - Blob upload gear active. 18:20:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:20:34 INFO - Copying logs to upload dir... 18:20:34 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1267.754404 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-11-23 18:20:34.012044) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:20:34.015512) ========= 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010052 build_url: 'https://queue.taskcluster.net/v1/task/QBD6jyF5RKSJ21ueQjF2GQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:20:34.066367) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:20:34.068553) ========= 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=fce1c2174958ee92775f70ae000001a3-1448329833.56497-1271520287 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004531 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:20:34.122095) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:20:34.122437) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:20:34.122740) ========= ========= Total master_lag: 0.22 =========