builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-009 starttime: 1447868900.54 results: success (0) buildid: 20151118073102 builduid: cd52a438bf9f403486269ec812ae245f revision: 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.536856) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.537380) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.537709) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.002937 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.552301) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.552609) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.561449) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.561766) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003757 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.609233) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.609584) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.610030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.610336) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-18 09:48:21-- 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% 215M=0s 2015-11-18 09:48:21 (215 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118153 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.741933) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.742380) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022619 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.798538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:20.799002) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d --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 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d --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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-18 09:48:21,546 truncating revision to first 12 chars 2015-11-18 09:48:21,546 Setting DEBUG logging. 2015-11-18 09:48:21,547 attempt 1/10 2015-11-18 09:48:21,547 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1d6155d7e6c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 09:48:21,844 unpacking tar archive at: mozilla-central-1d6155d7e6c9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.606091 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:21.418820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:21.419228) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:21.430570) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:21.430919) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 09:48:21.431374) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2015-11-18 09:48:21.431655) ========= /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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:22 INFO - MultiFileLogger online at 20151118 09:48:22 in /builds/slave/test-pgo 09:48:22 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 09:48:22 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:48:22 INFO - {'append_to_log': False, 09:48:22 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:48:22 INFO - 'blob_upload_branch': 'Firefox', 09:48:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:48:22 INFO - 'branch': 'Firefox', 09:48:22 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:22 INFO - 'config_files': ('talos/linux_config.py',), 09:48:22 INFO - 'default_actions': ('clobber', 09:48:22 INFO - 'read-buildbot-config', 09:48:22 INFO - 'download-and-extract', 09:48:22 INFO - 'populate-webroot', 09:48:22 INFO - 'create-virtualenv', 09:48:22 INFO - 'install', 09:48:22 INFO - 'run-tests'), 09:48:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:22 INFO - 'download_minidump_stackwalk': True, 09:48:22 INFO - 'download_symbols': 'ondemand', 09:48:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:22 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:22 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:22 INFO - 'installer_path': 'installer.exe', 09:48:22 INFO - 'log_level': 'info', 09:48:22 INFO - 'log_name': 'talos', 09:48:22 INFO - 'log_to_console': True, 09:48:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:22 INFO - 'opt_config_files': (), 09:48:22 INFO - 'pip_index': False, 09:48:22 INFO - 'sps_profile': False, 09:48:22 INFO - 'sps_profile_interval': 0, 09:48:22 INFO - 'suite': 'dromaeojs-e10s', 09:48:22 INFO - 'system_bits': '64', 09:48:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:22 INFO - 'title': 'talos-linux64-ix-009', 09:48:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:22 INFO - 'use_talos_json': True, 09:48:22 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:48:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:22 INFO - 'work_dir': 'build'} 09:48:22 INFO - ##### 09:48:22 INFO - ##### Running clobber step. 09:48:22 INFO - ##### 09:48:22 INFO - Running pre-action listener: _resource_record_pre_action 09:48:22 INFO - Running main action method: clobber 09:48:22 INFO - rmtree: /builds/slave/test-pgo/build 09:48:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:48:23 INFO - Running post-action listener: _resource_record_post_action 09:48:23 INFO - ##### 09:48:23 INFO - ##### Running read-buildbot-config step. 09:48:23 INFO - ##### 09:48:23 INFO - Running pre-action listener: _resource_record_pre_action 09:48:23 INFO - Running main action method: read_buildbot_config 09:48:23 INFO - Using buildbot properties: 09:48:23 INFO - { 09:48:23 INFO - "properties": { 09:48:23 INFO - "buildnumber": 548, 09:48:23 INFO - "product": "firefox", 09:48:23 INFO - "script_repo_revision": "production", 09:48:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:48:23 INFO - "repository": "", 09:48:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:48:23 INFO - "buildid": "20151118073102", 09:48:23 INFO - "slavename": "talos-linux64-ix-009", 09:48:23 INFO - "pgo_build": "True", 09:48:23 INFO - "basedir": "/builds/slave/test-pgo", 09:48:23 INFO - "project": "", 09:48:23 INFO - "platform": "ubuntu64_hw", 09:48:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:48:23 INFO - "slavebuilddir": "test", 09:48:23 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:48:23 INFO - "branch": "mozilla-central", 09:48:23 INFO - "repo_path": "mozilla-central", 09:48:23 INFO - "stage_platform": "linux64-pgo", 09:48:23 INFO - "builduid": "cd52a438bf9f403486269ec812ae245f", 09:48:23 INFO - "revision": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d" 09:48:23 INFO - }, 09:48:23 INFO - "sourcestamp": { 09:48:23 INFO - "repository": "", 09:48:23 INFO - "hasPatch": false, 09:48:23 INFO - "project": "", 09:48:23 INFO - "branch": "mozilla-central-linux64-pgo-talos", 09:48:23 INFO - "changes": [ 09:48:23 INFO - { 09:48:23 INFO - "category": null, 09:48:23 INFO - "files": [ 09:48:23 INFO - { 09:48:23 INFO - "url": null, 09:48:23 INFO - "name": "https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 09:48:23 INFO - } 09:48:23 INFO - ], 09:48:23 INFO - "repository": "", 09:48:23 INFO - "rev": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d", 09:48:23 INFO - "who": "sendchange", 09:48:23 INFO - "when": 1447868799, 09:48:23 INFO - "number": 6677997, 09:48:23 INFO - "comments": "", 09:48:23 INFO - "project": "", 09:48:23 INFO - "at": "Wed 18 Nov 2015 09:46:39", 09:48:23 INFO - "branch": "mozilla-central-linux64-pgo-talos", 09:48:23 INFO - "revlink": "", 09:48:23 INFO - "properties": [ 09:48:23 INFO - [ 09:48:23 INFO - "buildid", 09:48:23 INFO - "20151118073102", 09:48:23 INFO - "Change" 09:48:23 INFO - ], 09:48:23 INFO - [ 09:48:23 INFO - "builduid", 09:48:23 INFO - "cd52a438bf9f403486269ec812ae245f", 09:48:23 INFO - "Change" 09:48:23 INFO - ], 09:48:23 INFO - [ 09:48:23 INFO - "pgo_build", 09:48:23 INFO - "True", 09:48:23 INFO - "Change" 09:48:23 INFO - ] 09:48:23 INFO - ], 09:48:23 INFO - "revision": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d" 09:48:23 INFO - } 09:48:23 INFO - ], 09:48:23 INFO - "revision": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d" 09:48:23 INFO - } 09:48:23 INFO - } 09:48:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 09:48:23 INFO - Running post-action listener: _resource_record_post_action 09:48:23 INFO - ##### 09:48:23 INFO - ##### Running download-and-extract step. 09:48:23 INFO - ##### 09:48:23 INFO - Running pre-action listener: _resource_record_pre_action 09:48:23 INFO - Running main action method: download_and_extract 09:48:23 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:23 INFO - https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:48:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/test_packages.json 09:48:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/test_packages.json 09:48:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:48:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:48:24 INFO - Downloaded 1302 bytes. 09:48:24 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:48:24 INFO - Using the following test package requirements: 09:48:24 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:24 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:48:24 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'jsshell-linux-x86_64.zip'], 09:48:24 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:48:24 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:24 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:48:24 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:48:24 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:48:24 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:24 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:24 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:48:24 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:48:24 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')]} 09:48:24 INFO - https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:48:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:48:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:48:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:25 INFO - Downloaded 22461556 bytes. 09:48:25 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 09:48:25 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:48:25 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 09:48:26 INFO - Return code: 0 09:48:26 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 09:48:26 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')]} 09:48:26 INFO - https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:48:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:48:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:48:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:27 INFO - Downloaded 22461556 bytes. 09:48:27 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 09:48:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:48:27 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 09:48:28 INFO - Return code: 0 09:48:28 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')]} 09:48:28 INFO - https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:48:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:29 INFO - Downloaded 11130997 bytes. 09:48:29 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 09:48:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:29 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 09:48:29 INFO - Return code: 0 09:48:29 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')]} 09:48:29 INFO - https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:48:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:48:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:48:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NnHNMXZRQZms_YwsM7bkeA/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 09:48:31 INFO - Downloaded 57198390 bytes. 09:48:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:48:31 INFO - mkdir: /builds/slave/test-pgo/properties 09:48:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:48:31 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:48:31 INFO - Contents: 09:48:31 INFO - build_url:https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:48:31 INFO - Running post-action listener: _resource_record_post_action 09:48:31 INFO - Running post-action listener: set_extra_try_arguments 09:48:31 INFO - ##### 09:48:31 INFO - ##### Running populate-webroot step. 09:48:31 INFO - ##### 09:48:31 INFO - Running pre-action listener: _resource_record_pre_action 09:48:31 INFO - Running main action method: populate_webroot 09:48:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:31 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:48:31 INFO - '../fennec_ids.txt'], 09:48:31 INFO - 'tests': ['tcheck2']}, 09:48:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:31 INFO - '--tppagecycles', 09:48:31 INFO - '7'], 09:48:31 INFO - 'tests': ['tsvgm']}}, 09:48:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:48:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:48:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:48:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'tests': ['damp', 'tps']}, 09:48:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['damp', 'tps']}, 09:48:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:48:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:31 INFO - 'other': {'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore']}, 09:48:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore']}, 09:48:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore']}, 09:48:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore']}, 09:48:31 INFO - 'other_l64': {'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore']}, 09:48:31 INFO - 'other_nol64': {'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore']}, 09:48:31 INFO - 'svgr': {'tests': ['tsvgx', 09:48:31 INFO - 'tsvgr_opacity', 09:48:31 INFO - 'tart', 09:48:31 INFO - 'tscrollx', 09:48:31 INFO - 'cart']}, 09:48:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['tsvgx', 09:48:31 INFO - 'tsvgr_opacity', 09:48:31 INFO - 'tart', 09:48:31 INFO - 'tscrollx', 09:48:31 INFO - 'cart']}, 09:48:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'tests': ['tp5o']}, 09:48:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--e10s'], 09:48:31 INFO - 'tests': ['tp5o']}, 09:48:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--xperf_path', 09:48:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:31 INFO - 'tests': ['tp5n']}, 09:48:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--e10s', 09:48:31 INFO - '--xperf_path', 09:48:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:31 INFO - 'tests': ['tp5n']}}, 09:48:31 INFO - 'talos.zip': {'path': '', 09:48:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:31 INFO - Running post-action listener: _resource_record_post_action 09:48:31 INFO - ##### 09:48:31 INFO - ##### Running create-virtualenv step. 09:48:31 INFO - ##### 09:48:31 INFO - Running pre-action listener: _resource_record_pre_action 09:48:31 INFO - Running main action method: create_virtualenv 09:48:31 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:48:31 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 09:48:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:48:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:31 INFO - Using real prefix '/usr' 09:48:31 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:48:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:34 INFO - Installing pip.................done. 09:48:34 INFO - Return code: 0 09:48:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:34 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 09:48:34 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 09:48:34 INFO - Using env: {'DISPLAY': ':0', 09:48:34 INFO - 'HOME': '/home/cltbld', 09:48:34 INFO - 'LANG': 'en_US.UTF-8', 09:48:34 INFO - 'LANGUAGE': 'en_US:en', 09:48:34 INFO - 'LOGNAME': 'cltbld', 09:48:34 INFO - 'MAIL': '/var/mail/cltbld', 09:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:34 INFO - 'MOZ_NO_REMOTE': '1', 09:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:34 INFO - 'NO_EM_RESTART': '1', 09:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:34 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:34 INFO - 'SHELL': '/bin/bash', 09:48:34 INFO - 'SHLVL': '1', 09:48:34 INFO - 'TERM': 'linux', 09:48:34 INFO - 'TMOUT': '86400', 09:48:34 INFO - 'USER': 'cltbld', 09:48:34 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:34 INFO - '_': '/tools/buildbot/bin/python'} 09:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:34 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 09:48:34 INFO - Cleaning up... 09:48:34 INFO - Return code: 0 09:48:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:34 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 09:48:34 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 09:48:34 INFO - Using env: {'DISPLAY': ':0', 09:48:34 INFO - 'HOME': '/home/cltbld', 09:48:34 INFO - 'LANG': 'en_US.UTF-8', 09:48:34 INFO - 'LANGUAGE': 'en_US:en', 09:48:34 INFO - 'LOGNAME': 'cltbld', 09:48:34 INFO - 'MAIL': '/var/mail/cltbld', 09:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:34 INFO - 'MOZ_NO_REMOTE': '1', 09:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:34 INFO - 'NO_EM_RESTART': '1', 09:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:34 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:34 INFO - 'SHELL': '/bin/bash', 09:48:34 INFO - 'SHLVL': '1', 09:48:34 INFO - 'TERM': 'linux', 09:48:34 INFO - 'TMOUT': '86400', 09:48:34 INFO - 'USER': 'cltbld', 09:48:34 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:34 INFO - '_': '/tools/buildbot/bin/python'} 09:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:34 INFO - Downloading/unpacking psutil>=0.7.1 09:48:34 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 09:48:34 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 09:48:34 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 09:48:34 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 09:48:34 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 09:48:34 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 09:48:36 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 09:48:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:48:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:36 INFO - Installing collected packages: psutil 09:48:36 INFO - Running setup.py install for psutil 09:48:36 INFO - building 'psutil._psutil_linux' extension 09:48:36 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 09:48:36 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 09:48:36 INFO - building 'psutil._psutil_posix' extension 09:48:36 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 09:48:36 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 09:48:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:36 INFO - Successfully installed psutil 09:48:36 INFO - Cleaning up... 09:48:36 INFO - Return code: 0 09:48:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:48:36 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')]} 09:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:36 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')]} 09:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:36 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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:36 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 09:48:36 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 09:48:36 INFO - Using env: {'DISPLAY': ':0', 09:48:36 INFO - 'HOME': '/home/cltbld', 09:48:36 INFO - 'LANG': 'en_US.UTF-8', 09:48:36 INFO - 'LANGUAGE': 'en_US:en', 09:48:36 INFO - 'LOGNAME': 'cltbld', 09:48:36 INFO - 'MAIL': '/var/mail/cltbld', 09:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:36 INFO - 'MOZ_NO_REMOTE': '1', 09:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:36 INFO - 'NO_EM_RESTART': '1', 09:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:36 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:36 INFO - 'SHELL': '/bin/bash', 09:48:36 INFO - 'SHLVL': '1', 09:48:36 INFO - 'TERM': 'linux', 09:48:36 INFO - 'TMOUT': '86400', 09:48:36 INFO - 'USER': 'cltbld', 09:48:36 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:36 INFO - '_': '/tools/buildbot/bin/python'} 09:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:48:37 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 09:48:37 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 09:48:37 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 09:48:37 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 09:48:37 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 09:48:37 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 09:48:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:48:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:48:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:48:38 INFO - Installing collected packages: mozsystemmonitor 09:48:38 INFO - Running setup.py install for mozsystemmonitor 09:48:38 INFO - Successfully installed mozsystemmonitor 09:48:38 INFO - Cleaning up... 09:48:38 INFO - Return code: 0 09:48:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:48: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')]} 09:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:38 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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:38 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 09:48:38 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 09:48:38 INFO - Using env: {'DISPLAY': ':0', 09:48:38 INFO - 'HOME': '/home/cltbld', 09:48:38 INFO - 'LANG': 'en_US.UTF-8', 09:48:38 INFO - 'LANGUAGE': 'en_US:en', 09:48:38 INFO - 'LOGNAME': 'cltbld', 09:48:38 INFO - 'MAIL': '/var/mail/cltbld', 09:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:38 INFO - 'MOZ_NO_REMOTE': '1', 09:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:38 INFO - 'NO_EM_RESTART': '1', 09:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:38 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:38 INFO - 'SHELL': '/bin/bash', 09:48:38 INFO - 'SHLVL': '1', 09:48:38 INFO - 'TERM': 'linux', 09:48:38 INFO - 'TMOUT': '86400', 09:48:38 INFO - 'USER': 'cltbld', 09:48:38 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:38 INFO - '_': '/tools/buildbot/bin/python'} 09:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:39 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:39 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 09:48:39 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 09:48:39 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 09:48:39 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 09:48:39 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 09:48:39 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 09:48:40 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:48:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:40 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 09:48:40 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 09:48:40 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 09:48:40 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 09:48:40 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 09:48:40 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 09:48:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:48:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:48:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 INFO - Downloading docopt-0.6.1.tar.gz 09:48:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:48:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:41 INFO - Running setup.py install for blobuploader 09:48:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:41 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:48:41 INFO - Running setup.py install for requests 09:48:41 INFO - Running setup.py install for docopt 09:48:42 INFO - Successfully installed blobuploader requests docopt 09:48:42 INFO - Cleaning up... 09:48:42 INFO - Return code: 0 09:48:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:42 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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:42 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 09:48:42 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 09:48:42 INFO - Using env: {'DISPLAY': ':0', 09:48:42 INFO - 'HOME': '/home/cltbld', 09:48:42 INFO - 'LANG': 'en_US.UTF-8', 09:48:42 INFO - 'LANGUAGE': 'en_US:en', 09:48:42 INFO - 'LOGNAME': 'cltbld', 09:48:42 INFO - 'MAIL': '/var/mail/cltbld', 09:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:42 INFO - 'MOZ_NO_REMOTE': '1', 09:48:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:42 INFO - 'NO_EM_RESTART': '1', 09:48:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:42 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:42 INFO - 'SHELL': '/bin/bash', 09:48:42 INFO - 'SHLVL': '1', 09:48:42 INFO - 'TERM': 'linux', 09:48:42 INFO - 'TMOUT': '86400', 09:48:42 INFO - 'USER': 'cltbld', 09:48:42 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:42 INFO - '_': '/tools/buildbot/bin/python'} 09:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:42 INFO - Running setup.py (path:/tmp/pip-vPuYPT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:42 INFO - Running setup.py (path:/tmp/pip-Fe7o_u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:42 INFO - Running setup.py (path:/tmp/pip-LHbSrf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:42 INFO - Running setup.py (path:/tmp/pip-N7UDGE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:42 INFO - Running setup.py (path:/tmp/pip-p8NyGZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:42 INFO - Running setup.py (path:/tmp/pip-UKnHvB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:42 INFO - Running setup.py (path:/tmp/pip-Arkkw8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:42 INFO - Running setup.py (path:/tmp/pip-QGaHRo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:42 INFO - Running setup.py (path:/tmp/pip-PJpiWO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:42 INFO - Running setup.py (path:/tmp/pip-ZQ0XD3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:43 INFO - Running setup.py (path:/tmp/pip-sHfVAi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:43 INFO - Running setup.py (path:/tmp/pip-9M3YTq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:43 INFO - Running setup.py (path:/tmp/pip-2AKzbq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:43 INFO - Running setup.py (path:/tmp/pip-Ci3lAG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:43 INFO - Running setup.py (path:/tmp/pip-0SQIuU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:43 INFO - Running setup.py (path:/tmp/pip-gmjSsR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:43 INFO - Running setup.py (path:/tmp/pip-s6j370-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:43 INFO - Running setup.py install for manifestparser 09:48:43 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:48:43 INFO - Running setup.py install for mozcrash 09:48:43 INFO - Running setup.py install for mozdebug 09:48:43 INFO - Running setup.py install for mozdevice 09:48:43 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:48:43 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:48:43 INFO - Running setup.py install for mozfile 09:48:44 INFO - Running setup.py install for mozhttpd 09:48:44 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for mozinfo 09:48:44 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for mozInstall 09:48:44 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for mozleak 09:48:44 INFO - Running setup.py install for mozlog 09:48:44 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for moznetwork 09:48:44 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for mozprocess 09:48:44 INFO - Running setup.py install for mozprofile 09:48:44 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for mozrunner 09:48:44 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:48:44 INFO - Running setup.py install for mozscreenshot 09:48:45 INFO - Running setup.py install for moztest 09:48:45 INFO - Running setup.py install for mozversion 09:48:45 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:48:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:45 INFO - Cleaning up... 09:48:45 INFO - Return code: 0 09:48:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48:45 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')]} 09:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:45 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')]} 09:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:45 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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:45 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 09:48:45 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 09:48:45 INFO - Using env: {'DISPLAY': ':0', 09:48:45 INFO - 'HOME': '/home/cltbld', 09:48:45 INFO - 'LANG': 'en_US.UTF-8', 09:48:45 INFO - 'LANGUAGE': 'en_US:en', 09:48:45 INFO - 'LOGNAME': 'cltbld', 09:48:45 INFO - 'MAIL': '/var/mail/cltbld', 09:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:45 INFO - 'MOZ_NO_REMOTE': '1', 09:48:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:45 INFO - 'NO_EM_RESTART': '1', 09:48:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:45 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:45 INFO - 'SHELL': '/bin/bash', 09:48:45 INFO - 'SHLVL': '1', 09:48:45 INFO - 'TERM': 'linux', 09:48:45 INFO - 'TMOUT': '86400', 09:48:45 INFO - 'USER': 'cltbld', 09:48:45 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:45 INFO - '_': '/tools/buildbot/bin/python'} 09:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:45 INFO - Running setup.py (path:/tmp/pip-jUPIKd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:45 INFO - Running setup.py (path:/tmp/pip-izFEcc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:45 INFO - Running setup.py (path:/tmp/pip-yt276P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:45 INFO - Running setup.py (path:/tmp/pip-VCpz63-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:45 INFO - Running setup.py (path:/tmp/pip-bm92SM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:45 INFO - Running setup.py (path:/tmp/pip-Lb5RtP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:45 INFO - Running setup.py (path:/tmp/pip-BeJkeF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:46 INFO - Running setup.py (path:/tmp/pip-_tiZNv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:46 INFO - Running setup.py (path:/tmp/pip-_SIVzS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:46 INFO - Running setup.py (path:/tmp/pip-Rv6EK4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:46 INFO - Running setup.py (path:/tmp/pip-ogOiu9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:46 INFO - Running setup.py (path:/tmp/pip-Wfd3sx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:46 INFO - Running setup.py (path:/tmp/pip-ctWdQB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:46 INFO - Running setup.py (path:/tmp/pip-KXGAlp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:46 INFO - Running setup.py (path:/tmp/pip-RYdoR8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:46 INFO - Running setup.py (path:/tmp/pip-yNe1DX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:46 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)) 09:48:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:46 INFO - Running setup.py (path:/tmp/pip-XYFqIr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:46 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)) 09:48:46 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)) 09:48:46 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)) 09:48:46 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)) 09:48:46 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)) 09:48:46 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)) 09:48:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:46 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 09:48:46 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 09:48:46 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 09:48:46 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 09:48:46 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 09:48:46 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 09:48:48 INFO - Downloading blessings-1.5.1.tar.gz 09:48:48 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 09:48:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:48 INFO - Installing collected packages: blessings 09:48:48 INFO - Running setup.py install for blessings 09:48:48 INFO - Successfully installed blessings 09:48:48 INFO - Cleaning up... 09:48:48 INFO - Return code: 0 09:48:48 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:48:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:48:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:48:48 INFO - Reading from file tmpfile_stdout 09:48:48 INFO - Current package versions: 09:48:48 INFO - argparse == 1.2.1 09:48:48 INFO - blessings == 1.5.1 09:48:48 INFO - blobuploader == 1.2.4 09:48:48 INFO - docopt == 0.6.1 09:48:48 INFO - manifestparser == 1.1 09:48:48 INFO - mozInstall == 1.12 09:48:48 INFO - mozcrash == 0.16 09:48:48 INFO - mozdebug == 0.1 09:48:48 INFO - mozdevice == 0.47 09:48:48 INFO - mozfile == 1.2 09:48:48 INFO - mozhttpd == 0.7 09:48:48 INFO - mozinfo == 0.9 09:48:48 INFO - mozleak == 0.1 09:48:48 INFO - mozlog == 3.0 09:48:48 INFO - moznetwork == 0.27 09:48:48 INFO - mozprocess == 0.22 09:48:48 INFO - mozprofile == 0.27 09:48:48 INFO - mozrunner == 6.11 09:48:48 INFO - mozscreenshot == 0.1 09:48:48 INFO - mozsystemmonitor == 0.0 09:48:48 INFO - moztest == 0.7 09:48:48 INFO - mozversion == 1.4 09:48:48 INFO - psutil == 3.1.1 09:48:48 INFO - requests == 1.2.3 09:48:48 INFO - wsgiref == 0.1.2 09:48:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48:48 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')]} 09:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:48 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')]} 09:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:48 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 0x7f4b88bebc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf906c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10427c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', '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 09:48:48 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 09:48:48 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 09:48:48 INFO - Using env: {'DISPLAY': ':0', 09:48:48 INFO - 'HOME': '/home/cltbld', 09:48:48 INFO - 'LANG': 'en_US.UTF-8', 09:48:48 INFO - 'LANGUAGE': 'en_US:en', 09:48:48 INFO - 'LOGNAME': 'cltbld', 09:48:48 INFO - 'MAIL': '/var/mail/cltbld', 09:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:48 INFO - 'MOZ_NO_REMOTE': '1', 09:48:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:48 INFO - 'NO_EM_RESTART': '1', 09:48:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:48 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:48 INFO - 'SHELL': '/bin/bash', 09:48:48 INFO - 'SHLVL': '1', 09:48:48 INFO - 'TERM': 'linux', 09:48:48 INFO - 'TMOUT': '86400', 09:48:48 INFO - 'USER': 'cltbld', 09:48:48 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:48 INFO - '_': '/tools/buildbot/bin/python'} 09:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 INFO - Cleaning up... 09:48:49 INFO - Return code: 0 09:48:49 INFO - Running post-action listener: _resource_record_post_action 09:48:49 INFO - Running post-action listener: _start_resource_monitoring 09:48:49 INFO - Starting resource monitoring. 09:48:49 INFO - ##### 09:48:49 INFO - ##### Running install step. 09:48:49 INFO - ##### 09:48:49 INFO - Running pre-action listener: _resource_record_pre_action 09:48:49 INFO - Running main action method: install 09:48:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:48:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:48:49 INFO - Reading from file tmpfile_stdout 09:48:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:48:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:48:49 INFO - Reading from file tmpfile_stdout 09:48:49 INFO - Output received: 09:48:49 INFO - Usage: mozinstall [options] installer 09:48:49 INFO - Options: 09:48:49 INFO - -h, --help show this help message and exit 09:48:49 INFO - -d DEST, --destination=DEST 09:48:49 INFO - Directory to install application into. [default: 09:48:49 INFO - "/builds/slave/test-pgo"] 09:48:49 INFO - --app=APP Application being installed. [default: firefox] 09:48:49 INFO - mkdir: /builds/slave/test-pgo/build/application 09:48:49 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'] 09:48:49 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 09:49:03 INFO - Reading from file tmpfile_stdout 09:49:03 INFO - Output received: 09:49:03 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:49:03 INFO - Running post-action listener: _resource_record_post_action 09:49:03 INFO - ##### 09:49:03 INFO - ##### Running run-tests step. 09:49:03 INFO - ##### 09:49:03 INFO - Running pre-action listener: _resource_record_pre_action 09:49:03 INFO - Running main action method: run_tests 09:49:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:49:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:49:03 INFO - Python 2.7.3 09:49:03 INFO - Return code: 0 09:49:03 INFO - grabbing minidump binary from tooltool 09:49: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')]} 09:49:03 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 0x1086d70>, '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 0x1087b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:49:03 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 09:49:03 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 09:49:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:03 INFO - Return code: 0 09:49:03 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:49:03 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:49:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:49:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:49:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:49:03 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/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:49:03 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/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost 09:49:03 INFO - Using env: {'DISPLAY': ':0', 09:49:03 INFO - 'HOME': '/home/cltbld', 09:49:03 INFO - 'LANG': 'en_US.UTF-8', 09:49:03 INFO - 'LANGUAGE': 'en_US:en', 09:49:03 INFO - 'LOGNAME': 'cltbld', 09:49:03 INFO - 'MAIL': '/var/mail/cltbld', 09:49:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:49:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:03 INFO - 'MOZ_NO_REMOTE': '1', 09:49:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:03 INFO - 'NO_EM_RESTART': '1', 09:49:03 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:49:03 INFO - 'PWD': '/builds/slave/test-pgo', 09:49:03 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:49:03 INFO - 'SHELL': '/bin/bash', 09:49:03 INFO - 'SHLVL': '1', 09:49:03 INFO - 'TERM': 'linux', 09:49:03 INFO - 'TMOUT': '86400', 09:49:03 INFO - 'USER': 'cltbld', 09:49:03 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425', 09:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:03 INFO - '_': '/tools/buildbot/bin/python'} 09:49:03 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/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] with output_timeout 3600 09:49:04 INFO - mozversion INFO | application_buildid: 20151118073102 09:49:04 INFO - mozversion INFO | application_changeset: 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d 09:49:04 INFO - mozversion INFO | application_display_name: Nightly 09:49:04 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:04 INFO - mozversion INFO | application_name: Firefox 09:49:04 INFO - mozversion INFO | application_remotingname: firefox 09:49:04 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:49:04 INFO - mozversion INFO | application_vendor: Mozilla 09:49:04 INFO - mozversion INFO | application_version: 45.0a1 09:49:04 INFO - mozversion INFO | platform_buildid: 20151118073102 09:49:04 INFO - mozversion INFO | platform_changeset: 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d 09:49:04 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:49:04 INFO - mozversion INFO | platform_version: 45.0a1 09:49:04 INFO - 2015-11-18 09:49:04,069 DEBUG : using testdate: 1447868944 09:49:04 INFO - 2015-11-18 09:49:04,069 DEBUG : actual date: 1447868944 09:49:04 INFO - 2015-11-18 09:49:04,117 INFO : starting webserver on 'localhost:38298' 09:49:04 INFO - 2015-11-18 09:49:04,118 INFO : Starting test suite talos-linux64-ix-009.e 09:49:04 INFO - 2015-11-18 09:49:04,118 INFO : Starting test dromaeo_css 09:49:04 INFO - 2015-11-18 09:49:04,118 DEBUG : operating with platform_type : linux_ 09:49:04 INFO - 2015-11-18 09:49:04,119 INFO : Initialising browser for dromaeo_css test... 09:49:04 INFO - 2015-11-18 09:49:04,135 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSR1BGK/profile http://localhost:38298/getInfo.html 09:49:09 INFO - 2015-11-18 09:49:09,466 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:49:09 INFO - 2015-11-18 09:49:09,466 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:49:09 INFO - 2015-11-18 09:49:09,466 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:49:09 INFO - 2015-11-18 09:49:09,466 DEBUG : BROWSER_OUTPUT: __metrics 09:49:11 INFO - 2015-11-18 09:49:11,002 INFO : Browser initialized. 09:49:11 INFO - 2015-11-18 09:49:11,003 INFO : Running cycle 1/1 for dromaeo_css test... 09:49:11 INFO - 2015-11-18 09:49:11,003 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSR1BGK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:49:22 INFO - 2015-11-18 09:49:22,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 142012416 09:49:22 INFO - 2015-11-18 09:49:22,812 DEBUG : BROWSER_OUTPUT: 09:50:12 INFO - 2015-11-18 09:50:12,422 DEBUG : BROWSER_OUTPUT: 1447869012421 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] 09:52:20 INFO - 2015-11-18 09:52:20,684 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38298/tests/dromaeo/cssquery-ext.html) 09:52:21 INFO - 2015-11-18 09:52:21,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 147632128 09:52:21 INFO - 2015-11-18 09:52:21,858 DEBUG : BROWSER_OUTPUT: 09:55:23 INFO - 2015-11-18 09:55:23,852 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/dromaeo/cssquery-ext.html (next: http://localhost:38298/tests/dromaeo/cssquery-jquery.html) 09:55:24 INFO - 2015-11-18 09:55:24,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 141115392 09:55:24 INFO - 2015-11-18 09:55:24,425 DEBUG : BROWSER_OUTPUT: 09:58:26 INFO - 2015-11-18 09:58:26,086 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38298/tests/dromaeo/cssquery-mootools.html) 09:58:26 INFO - 2015-11-18 09:58:26,673 DEBUG : BROWSER_OUTPUT: RSS: Main: 141828096 09:58:26 INFO - 2015-11-18 09:58:26,674 DEBUG : BROWSER_OUTPUT: 10:01:28 INFO - 2015-11-18 10:01:28,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38298/tests/dromaeo/cssquery-prototype.html) 10:01:28 INFO - 2015-11-18 10:01:28,932 DEBUG : BROWSER_OUTPUT: RSS: Main: 141111296 10:01:28 INFO - 2015-11-18 10:01:28,932 DEBUG : BROWSER_OUTPUT: 10:04:31 INFO - 2015-11-18 10:04:31,285 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38298/tests/dromaeo/cssquery-yui.html) 10:04:31 INFO - 2015-11-18 10:04:31,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 140926976 10:04:31 INFO - 2015-11-18 10:04:31,886 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/dromaeo/cssquery-yui.html (next: http://localhost:38298/tests/dromaeo/cssquery-dojo.html) 10:07:34 INFO - 2015-11-18 10:07:34,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 144400384 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3190.6968837960394;3260.9510857337127;3278.8196649184315;3295.686909743575;3305.1406634275095;5284.128118860383;5465.863353416166;5491.3410390753115;5499.917501237484;5507.146392309189;5334.466553344663;5353.330833645794;5376.489233522819;5393.622446428752;5444.564434845212;4708.223143181388;4941.925871111936;5145;5154.175331946889;5157.896842063156;4810.927836082462;5071.391433028042;5074.441811400743;5083.974580127113;5142.640015198937;4849.739067842369;4927.236278376839;4997.775100120503;5019.397672279313;5023.472535383787;4993.575546078588;4997.90004199914;5039.6220283478515;5041.72270525121;5058.140116180258;4901.950980490185;5009.0482808266315;5014.348134742497;5043.87390315245;5064.80558325025;5182.4299327073995;5213.4004589472315;5221.086309895753;5284.048871203182;5288.286081379003;5830.3586605473365;5851.122331650244;5861.2087368205175;5869.354371019184;5888;5748.7125643717645;5861.824145275648;5880.852978675524;5887.058070708709;5912.201852749867;5466.562674985991;5495.6153069285165;5498.80754173602;5509.862253443656;5555.583331250173;5987.880242395127;6006.219191505076;6012.338642749294;6014.338422773491;6028.125921741411;5677.460641239076;5778.422157784188;5781.364049954585;5807.390224026501;5815.505682017076;5903.675297858573;5917.940820591825;5918.023526118142;5939.227900372924;5959.851003724942;5812.94187058124;5858.970705146532;5884.382139875252;5888.676122813201;5889.4993925515955;5633.943660563424;5720.771169153188;5732.684702341411;5809.50619197373;5880.26496687914;4451.888702782457;4475.731456112644;4485.304777759453;4492.213862574036;4518.751468669189;3961.900952476211;3982.104026594051;4002.479677641946;4004.4994375703036;4051.6961227908023;5402;5508.394076651565;5511.53151982086;5606.355269144101;5608.383077861432;5850.824475265749;5867.090600956859;5877.647341159544;5902.940970590325;5938.257717785277;5487.75305111271;5492.5331346835965;5512.779488820483;5526.50261476469;5527.060399732055;4907.214845624683;4935.087008903364;4984.37695288089;4998.500149984972;5047.394312682428;6021.7591296347655;6075.969620151871;6077.270727512727;6092.939070609325;6098.72555734993;5680.062789639664;5872.265966754156;5877.353491115889;5883.294004719461;5892.381299963442;5799.768009279582;6006.039033754579;6009.06859436788;6086.786962456293;6125.877482450237;5712.457316554921;5824.825255242349;5844.240248767633;5863.325717542537;5864.706764661664;5685.715714214355;5802.94197058024;5888.17565540816;5889.5582831286965;5899.528037756883;5609.579281553818;5631.8592035199445;5652.943470565242;5684.687342196054;5751.367349591458;5568.248286481273;5694.971525142348;5724.3703192647945;5763.8559036024435;5792.507636850914;5418.864528386822;5642.492175704207;5647.29408823897;5695.629784064023;5705.942940570541;5657;5658.7736490541565;5727.739897222605;5825.359210486928;5865.413458654271;5672.14917762339;5682.374938756816;5705.914411283917;5709.885802284014;5732.799352022659 10:07:34 INFO - 2015-11-18 10:07:34,504 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.6871536297356;587.1691556447624;592.869568694887;596.7404179182056;609.4636719686677;38973.80513097434;39476.80261598688;39717.60282397175;39729.60270397295;39768.00579985498;41902.58097419025;42103.57896421035;42369.15261694764;42398;42418.787906060425;8726.47641141532;8833.337499687517;8856.158664926821;8868.068852770462;8908.643654253821;8676.652933882637;8737.21365077143;8764.649414023432;8802.735917922473;8809.559522023874;8747.868781968276;8832.8675069874;8870.157335053193;8872.20150186483;8873.378863479558;8634.481931084156;8637.0499245083;8689;8690.913090869073;8714.607842647096;8613.353998450142;8674.653013879437;8748.78128046804;8749.03760586335;8751.78120546985;39325.60674393248;40131.19737605232;40143.799281003405;40221.195576088314;40224.19551608981;3230.5800245968107;3502.912427189315;3515.666011728907;3839.827207775657;3958.3580730712742;29005.32868501158;29927.551086733718;29988.70011299881;30181.09456716302;30931.536026959613;3477.3393055319407;3503.7722548034294;3542.4863394807867;3553.8272370118057;3590.7845529268066;257172;258266;258882;258975;260072.6996365025;41300;57476;57886;57895.421045789844;57991.71004144952;42894.78552607217;43182.92042698958;43376.78311608485;43420.3486947696;43439;13262.403191856389;13294.734105317839;13310.267935263653;13327.600171994856;13352.6661833453;10523.158147348193;10528.263021588415;10536.736581585521;10545.050945414796;10572.52423640938;14269.215193164475;14308.856911430752;14316;14387.856121438652;14428.927855360866;5748.913766293509;5883.410939467222;5890.8821823563285;5952.255968003999;5977.057456326711;868.8349213649512;972.2465089555651;978.3249557805092;1032.695354870315;1102.9172812039128;27998.56606554287;28171.154865354074;28192.29519261994;28637.85681071623;28876;975.8145952269047;1005.7435353984687;1024.6464969585481;1071.9816174633972;1081.5187241677536;14915.328810203568;16280.918595406947;16769.580760481087;16925.576860578338;17130.828691713174;390794;391101;393509;394508;399008;53854;53945.730271348395;54042.729786350814;54299;54337.1849422259;1314.750197462479;1350.7906274527463;1355.5323413422354;1359.2931675528866;1380.8412032616377;29177.416451671274;30378.392432151675;32267.838660806545;33405.83297083499;33573.49639755455;47607.28589071236;48304.516954830004;48342.516574833804;48377.27434088562;48390;294228;299287;299663;299955;300698;40494.79752601218;45039.774801127096;45265;45368.31947520856;45549.77225113853;46527.76736116433;47013.76493117512;47204;47333.7633311845;47412.2888156671;38463.807680961414;38827.6117238824;39952.800235998635;40019;40477;39749.602503975744;40326.19347612972;42196;42632.78753993585;44059.33910991402;38159.42760858645;38322.233555329294;38337;38759.41860872033;38789.22421551609;34446.655533445344;35281.47077793886;35329.82335088308;35576.110597235274;36418;26832.463350733266;27033.566978971987;27187.320316992234;27192.72807271902;27246.182614521596 10:07:34 INFO - 2015-11-18 10:07:34,505 DEBUG : BROWSER_OUTPUT: |2;jquery.html;347.22568671861745;349.9107727529481;351.4359453077811;351.8574977134261;353.840771652756;12125.393730313494;12527;16833.915830420847;17732.82267177328;19157.616847663056;11527.481263343148;11570.132240082006;11596.072314214864;11626.593069242579;11902.583409580666;4518.390017347665;4606.0327331260305;4606.5393460654;4610.101030299093;4611.976940115312;4451.755153466558;4561.361409402686;4562.954370456286;4563.995920897397;4569.063342014895;4473.776311184444;4522.479914809806;4672.2057250267535;4787.305840653104;4808.61531077513;4745.620350371962;4755.50067242938;4774.021325628238;4774.1645212087915;4784.808607655689;4173.457450531442;4291.892702682427;4303.569643035703;4305.806238719265;4310.741355518679;12924.54764083262;12964.157329773534;13229.140105893086;13457.596272111852;13495.66260843477;1819.645169192008;1930.9903450482657;2011.6379051770675;2038.6330460517102;2042.8365730741505;9765.755856103655;9872.802543949152;9946.452945088018;9967.302288839785;9985.251106167067;1618.207078531523;1705.0707364486384;1731.333436626895;1732.8267173282698;1769.7699299091164;58680;60355.69822150861;60655.39344606541;61413.6929315355;61747;6697.464202863759;6712.328767123347;6835.58986460814;6837.290677330668;6858.485613579001;8145.633446494922;8218.671253149807;8220.671173153007;8234.917650823534;8235.258826705625;5191.650170955579;5253.527182553588;5364.061289274361;5385.353757549119;5393.298871146804;5404.216388623706;5425.83722488326;5455.18172274162;5465.906818636209;5467.917981230285;3169.904902852918;3206.246532064963;3219.3400352927592;3231.369882872817;3237.4496335623003;1136.8010598145293;1138.9886101139048;1141.0814294493014;1147.713071732067;1163.1102206811795;418.4769038701623;418.5563302898937;419.264191344189;433.19425867885707;436.76633001344766;10011.199104071657;10393.324433911923;10431.321964072311;10431.478426078666;10523.684289471328;408.15104582468393;422.81396185678204;430.2814300118809;433.17696376884174;438.9275769497997;5604.243427137284;5769.105788602689;5778.913316300259;5865.178874957509;5868.2664666916635;500568;501229;504787;507682;518157;74456;76783.61608191923;80466;81429;81587.59206203932;536.8013834881119;547.5756288876073;552.1634723394061;552.4447929830494;555.3752028967411;11190.94404527972;11464.140189485988;11589.420528973686;11596.304221746723;11654.76690466169;33970.32059358848;34522.82738586291;35044.29911401809;35177.65812247832;35657.1085722859;46654.53345466502;47254.527454725016;47561.76219118882;48446;48882.5111748878;6893.103896493424;6920.757773477904;6948.0272761812375;6959.408450281783;7001.299870012958;12459.626211213677;12528.310942898228;12655.367231638196;12810.935945320214;12870.034747393796;9384.812303754023;10624.309419887682;10783.083437907866;10853.945730271298;11015.944920275348;10310.175185985254;10551.261411701107;10699.94650026776;10844.620438284623;10932.234743567873;10726.034656880918;10727.839082413926;10974.067204287725;10985.61550345734;10988.780224395627;6723.428508576826;6770.45836333102;6831.590104593542;6851.725930962905;6860.622665753432;10573.52419141156;10633.946830266108;10693.304935179189;10698.090662293793;10789.838152427563 10:07:34 INFO - 2015-11-18 10:07:34,507 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1297.416162726773;1703.3782669325697;1716.7167417376133;1739.6694628020675;1767.5669460982058;7166;7482.513636613617;7562.848743025136;7584.924150758491;7711.4216433767415;7528.623568821565;7543.132539757929;7556.659950302236;7602.429817763662;7604.88592671109;2171.0447403142643;2198.230619283254;2203.43812327857;2204.5370472200775;2204.911803527857;2229.4314949687805;2253.763354847734;2257.1874125314876;2257.8645281283093;2260.886955652219;2201.0535469748;2244.9438764030865;2266.478709896725;2268.7844654757773;2278.943026424336;2220.0564759977105;2244.3267019893965;2256.729192496911;2268.931932042033;2273.193016479151;2188.9671654925187;2243.775622437743;2257.3115199864037;2270.648049552322;2275.7041584594062;7457.664405101784;7557.659905304219;7602.429817763635;7609.619519024027;7609.847803043908;4548.476925153584;4582.587567118942;4608.1244563533155;4613.746243956581;4649.465311489154;3007.7142671446177;3016.065019843855;3072.830994295313;3102.720755132049;3102.7672924530752;1010.8483727440869;1022.3865680591659;1032.3083534032216;1042.6194439029775;1068.5779117248644;10199.592016319339;10239.078482936571;10304.484775761257;10318.690439286833;10374.63688770897;3923.156521347924;4019.8191081401405;4055.9391609125887;4060.0661847774586;4100.01599616096;7943.9602801986775;7949.523028618301;7950.284474397332;7961.72133975308;7972.322352600094;4533.886652833639;4536.070105628338;4565.0641618468135;4569.977150114228;4570.405847239904;4192.161567686475;4193.874183774492;4199.517055538591;4203.76879271643;4206.705530612889;3372.696457318853;3391.593008838955;3405.0636075079055;3405.8467368968454;3421.0934102462866;1059.1209296284067;1069.4599227390274;1069.6737495063924;1072.1958531101673;1080.3409919948824;1058.227493929436;1070.2936062198912;1116.4696769034806;1228.5577192210797;1348.689801345696;6900.447964162855;6981.127359080115;6986.126734158231;7018.052562903943;7311.195768465464;925.6251218256617;934.780326623243;942.7784470649392;943.1747221181466;944.9055094490496;5493.093639549509;5496.725163741797;5534.806281780118;5547.417521160301;5556.444355564411;8811.559422028873;8823.514706691065;8894.59974301158;9070.455772653662;9083.81832363349;8790.560471976376;8838;8925.152110549487;8933.508657023796;8946.731598051938;6711.731530738716;6750.932490675129;6777.085093512412;6849.280825513349;6860.279670634612;6643.30245324234;6671.39957403836;6681.832954176184;6717.093192418961;6717.328267173243;7325.890111648223;7493.4379921505015;7500.13748418917;7548.320651141424;7622.504537205064;7536.171021187774;7652.426068044808;7685.308322251024;7720.8455830884195;7736.949708327185;6493.480521558358;6519.576227545194;6554.4756419487285;6563.540552161303;6654.401103900672;7207.9279207207255;7419.146798118286;7437.516561423707;7437.665305061393;7453.590052547162;6576.80269591913;6933.306669333027;6992.895106573507;7001.541751628948;7133.3936615388275;6753.054572359972;6782.491313151632;6785.9660701696175;6832.760853370109;6927.480438967198;6738.35985581369;6856.862862742618;6910.8617827644175;6911.032455456139;6930.792076237721;6165.599236049642;6193.38066193377;6211.472024877936;6219.657918814509;6223.688815559294;6281.623102613858;6388.233411990591;6391.296957334783;6451.806445806634;6466.159399278064 10:07:34 INFO - 2015-11-18 10:07:34,510 DEBUG : BROWSER_OUTPUT: |4;prototype.html;313.7317593457594;319.33418821756635;321.34606076634026;323.80733463589166;325.3135883286267;6197.721102550385;6305;6385.138006369145;6410.839729006777;6526;6275.7489700411925;6308.116863639094;6315.147455093561;6331.936680633204;6339.112524246598;4522.163399771053;4569.428821397325;4649.558291962258;4800.807967681298;4807.062622788558;4723.645726570521;4747.786349614275;4770.0698363819065;4774.379330687023;4777.832775852846;4676.696014759047;4701.670883038189;4703.388559487262;4711.222648263032;4711.764411779414;4696.53034696531;4705.905881882378;4716.339712440261;4723.07899959508;4725.787339569728;4000.4199391088127;4055.918881622351;4062.5124985001694;4069.633732964018;4075.9184816303805;5930.733117009745;5964.761409543613;5967.731452084667;5973.790917317917;5979.073243647242;5477.534409575191;5479.123340265538;5482.451754824526;5484.670919744828;5496.203050557647;6470.967645161792;6595.505337099688;6620.238672552622;6662.633555154464;6669;4472.418585583853;4475.641948644101;4478.776061196927;4483.820647174109;4496.7077139985795;6907.620080895549;6971.8605627887155;6985.05701730265;6999.89500157493;7004.054452648878;4536.5236650151255;4561.156186105514;4593.173228818845;4601.953980460153;4606.424196975378;4526.411566496335;4648.2562789953445;4658;4659.277811939155;4661.906761864744;5414.18787181918;5415.48552887475;5429.891402171935;5447.618666693294;5450.454954504517;6679.064930909676;6697.430718388894;6713.966430167916;6732.091167692299;6732.360425759545;1641.65525239701;1643.9753403698953;1651.9091449970367;1660.9335626575044;1662.9085400302858;1048.2819268800895;1054.7099547624464;1060.0618452669444;1064.834950582661;1084.7233955341496;6348.269948955837;6413.871722565523;6445.774397896051;6463.224413070461;6536.640484773288;6954.79135625932;6982.615956122462;6984.266652001466;6991.370776630128;6995.475339349569;6469.482441404676;6480.092787009822;6503.154589903283;6510.544261901716;6516.152900123048;3735.1409175889153;3777.773333599993;3785.8107094645707;3801;3810.2379524094736;7225.132984041844;7230.2046774854725;7281.5995120267835;7282.70869165238;7283.344498995116;7158.2483839197175;7169.89245161323;7175.677094530759;7178.748743793943;7190.280971902873;7082.468814838908;7190.712371505081;7230.674619642129;7280.781576552712;7282.053333066668;6949.7567585134275;6963.79108626742;6983.580985140908;6984.161900571964;6984.685689143899;6827.146606674166;6876.793696189123;6881.277465866212;6895.241523432318;6905.447564194752;7142.750003749844;7215.567065976058;7283.380912622486;7298.817529561591;7306.890396643948;4621.353010578455;4689.460712018162;4691.530846915418;4692.366530518337;4694.483606803181;6909.136357955255;6935.895961560682;6942.479314051366;6946.131733533309;6947.062146610143;6650.434713049445;6765.86468270622;6769.120014398097;6769.153855768029;6771;6717.227518835201;6747.392734653905;6749.156355455568;6760.290169532325;6761.729530818712;6513.804585862432;6619.4042536170045;6635.170603674541;6644.800655980328;6657.167854018247;6466.838329041811;6630.53586248958;6733.730650773914;6780.72877084911;6787.62668053242;6428.55000149985;6551.967240163769;6568;6573.3098024706715;6609.702563384563 10:07:34 INFO - 2015-11-18 10:07:34,510 DEBUG : BROWSER_OUTPUT: |5;yui.html;718.8382613911871;753.6834529497611;777.2848978939371;795.7771823889314;842.3808500751946;13606.591802245935;13656.317184140802;13887.305634718261;13908.860911390882;13916.582502524927;12979.740405191891;13058.738825223514;13067.803982940264;13076.215427074381;13078.346082695874;743.71738739279;744.3077937518106;754.7132089805866;756.4364548411442;771.5756334016297;717.4977515738977;719.5358993449222;725.3508110241331;726.6439444672098;737.7454778101546;748.4087570819073;799.448380617375;824.3446460064263;831.1314676163394;832.5004997001781;812.4272387966499;814.5560669435142;817.3502065857627;821.6795449774593;823.3331001888489;541.9729013549306;544.7031367904501;551.3136145498839;554.4870994330229;577.7804434314934;13713.108647937852;13729.313534323244;13788.517401890986;13792.862071379359;13795.172289662652;9919.206463482977;10045.748856278578;10110.180167569835;10149.441780702133;10453.216008799294;9807.660710106933;9842.90157098427;9953.452560109192;10042.748931276703;10275.948620256851;3174.68253174683;3261.951070733941;3273.3289675616443;3276.459384201604;3352.9664703352896;45998.31002534931;46028.7698561505;46075.76962115168;46084;46589.76705116487;7348.412127029825;7572.242775722384;7593.924060759322;7709.4603377763015;7769.3784497240085;11930.88069119315;11961.58134465307;11969.880301196876;11993.34036627994;12005.939970300093;6405.29541750407;6527.706253218522;6537.378948999837;6675.232348279913;6729.5625784323865;4315.719478233905;4328;4378;4393.934090988638;4498.6626003049905;2716.1172618898936;2721.6733991921096;2726.209399274228;2730.1400058981358;2732.6037724529833;414.376363572821;418.2054097215254;422.0482811260588;428.80489377333237;431.4865310280756;441.7680717623209;455.3829560944938;484.41144010027983;577.4860374266906;658.6311665467354;8438.493690378597;8711.695090671796;8750.562471876381;8799.164079412445;8800.119988001148;484.3001862309;569.9059655156852;598.1327075740194;600.2526854066715;617.237711426388;3887.654791294602;3945.151792364655;4036.233115708006;4176.561461046607;4246.171996460722;53154.46845531573;53680.73159634177;53740;53780.46219537833;53983;49054.26418603724;50468;51047.74476127596;51299.97400051978;51724;47603.85782639789;48002.519974799805;48012.03975920462;48696;48696.269555956686;8751.168638979287;8840.911590884009;8853.15894989975;8973.237274831452;9015.14356136166;32647;33744;33761.155971099935;34515.654843451244;36622.63377366299;44315.33526997162;46101.30848037215;46133.53866461293;46177.53822461867;46636.53363466322;8355.248027677539;8362.331013518782;8372.07907130203;8427.5786210688;8509.744707658532;11571.768564628828;11885.405729713308;12209.206401583868;12362.3818809061;12678.429470673658;11734.354610496504;11806.173567850168;12003.939980300043;12406.565770198;12434.502619895104;11713.88286117128;11867.347295898808;12008.159428840247;12475.625731228078;12506.874931250572;11308.717282068013;11516.078713702716;11682.94158529202;11748.941255293668;12145.149839511503;9454.716358509255;9552.42685438876;9757.170640495637;9882.11061004513;9899.1585715215;8927.24118449939;9155.542222888696;10016.148627366463;10165.932577079597;10504.159667226757 10:07:34 INFO - 2015-11-18 10:07:34,510 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:07:34 INFO - 2015-11-18 10:07:34,510 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:07:34 INFO - 2015-11-18 10:07:34,510 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,281 10:07:34 INFO - 2015-11-18 10:07:34,510 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: __startTimestamp1447870054501__endTimestamp 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5434.77 Median:5652.94 stddev:606.49 (10.7%) stddev-sans-first:582.24 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: Values: 3190.7 3261.0 3278.8 3295.7 3305.1 5284.1 5465.9 5491.3 5499.9 5507.1 5334.5 5353.3 5376.5 5393.6 5444.6 4708.2 4941.9 5145.0 5154.2 5157.9 4810.9 5071.4 5074.4 5084.0 5142.6 4849.7 4927.2 4997.8 5019.4 5023.5 4993.6 4997.9 5039.6 5041.7 5058.1 4902.0 5009.0 5014.3 5043.9 5064.8 5182.4 5213.4 5221.1 5284.0 5288.3 5830.4 5851.1 5861.2 5869.4 5888.0 5748.7 5861.8 5880.9 5887.1 5912.2 5466.6 5495.6 5498.8 5509.9 5555.6 5987.9 6006.2 6012.3 6014.3 6028.1 5677.5 5778.4 5781.4 5807.4 5815.5 5903.7 5917.9 5918.0 5939.2 5959.9 5812.9 5859.0 5884.4 5888.7 5889.5 5633.9 5720.8 5732.7 5809.5 5880.3 4451.9 4475.7 4485.3 4492.2 4518.8 3961.9 3982.1 4002.5 4004.5 4051.7 5402.0 5508.4 5511.5 5606.4 5608.4 5850.8 5867.1 5877.6 5902.9 5938.3 5487.8 5492.5 5512.8 5526.5 5527.1 4907.2 4935.1 4984.4 4998.5 5047.4 6021.8 6076.0 6077.3 6092.9 6098.7 5680.1 5872.3 5877.4 5883.3 5892.4 5799.8 6006.0 6009.1 6086.8 6125.9 5712.5 5824.8 5844.2 5863.3 5864.7 5685.7 5802.9 5888.2 5889.6 5899.5 5609.6 5631.9 5652.9 5684.7 5751.4 5568.2 5695.0 5724.4 5763.9 5792.5 5418.9 5642.5 5647.3 5695.6 5705.9 5657.0 5658.8 5727.7 5825.4 5865.4 5672.1 5682.4 5705.9 5709.9 5732.8 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48677.71 Median:39723.60 stddev:84552.27 (212.9%) stddev-sans-first:84712.29 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: Values: 563.7 587.2 592.9 596.7 609.5 38973.8 39476.8 39717.6 39729.6 39768.0 41902.6 42103.6 42369.2 42398.0 42418.8 8726.5 8833.3 8856.2 8868.1 8908.6 8676.7 8737.2 8764.6 8802.7 8809.6 8747.9 8832.9 8870.2 8872.2 8873.4 8634.5 8637.0 8689.0 8690.9 8714.6 8613.4 8674.7 8748.8 8749.0 8751.8 39325.6 40131.2 40143.8 40221.2 40224.2 3230.6 3502.9 3515.7 3839.8 3958.4 29005.3 29927.6 29988.7 30181.1 30931.5 3477.3 3503.8 3542.5 3553.8 3590.8 257172.0 258266.0 258882.0 258975.0 260072.7 41300.0 57476.0 57886.0 57895.4 57991.7 42894.8 43182.9 43376.8 43420.3 43439.0 13262.4 13294.7 13310.3 13327.6 13352.7 10523.2 10528.3 10536.7 10545.1 10572.5 14269.2 14308.9 14316.0 14387.9 14428.9 5748.9 5883.4 5890.9 5952.3 5977.1 868.8 972.2 978.3 1032.7 1102.9 27998.6 28171.2 28192.3 28637.9 28876.0 975.8 1005.7 1024.6 1072.0 1081.5 14915.3 16280.9 16769.6 16925.6 17130.8 390794.0 391101.0 393509.0 394508.0 399008.0 53854.0 53945.7 54042.7 54299.0 54337.2 1314.8 1350.8 1355.5 1359.3 1380.8 29177.4 30378.4 32267.8 33405.8 33573.5 47607.3 48304.5 48342.5 48377.3 48390.0 294228.0 299287.0 299663.0 299955.0 300698.0 40494.8 45039.8 45265.0 45368.3 45549.8 46527.8 47013.8 47204.0 47333.8 47412.3 38463.8 38827.6 39952.8 40019.0 40477.0 39749.6 40326.2 42196.0 42632.8 44059.3 38159.4 38322.2 38337.0 38759.4 38789.2 34446.7 35281.5 35329.8 35576.1 36418.0 26832.5 27033.6 27187.3 27192.7 27246.2 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,511 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25951.81 Median:433.19 stddev:83175.89 (19201.0%) stddev-sans-first:83387.00 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: Values: 347.2 349.9 351.4 351.9 353.8 12125.4 12527.0 16833.9 17732.8 19157.6 11527.5 11570.1 11596.1 11626.6 11902.6 4518.4 4606.0 4606.5 4610.1 4612.0 4451.8 4561.4 4563.0 4564.0 4569.1 4473.8 4522.5 4672.2 4787.3 4808.6 4745.6 4755.5 4774.0 4774.2 4784.8 4173.5 4291.9 4303.6 4305.8 4310.7 12924.5 12964.2 13229.1 13457.6 13495.7 1819.6 1931.0 2011.6 2038.6 2042.8 9765.8 9872.8 9946.5 9967.3 9985.3 1618.2 1705.1 1731.3 1732.8 1769.8 58680.0 60355.7 60655.4 61413.7 61747.0 6697.5 6712.3 6835.6 6837.3 6858.5 8145.6 8218.7 8220.7 8234.9 8235.3 5191.7 5253.5 5364.1 5385.4 5393.3 5404.2 5425.8 5455.2 5465.9 5467.9 3169.9 3206.2 3219.3 3231.4 3237.4 1136.8 1139.0 1141.1 1147.7 1163.1 418.5 418.6 419.3 433.2 436.8 10011.2 10393.3 10431.3 10431.5 10523.7 408.2 422.8 430.3 433.2 438.9 5604.2 5769.1 5778.9 5865.2 5868.3 500568.0 501229.0 504787.0 507682.0 518157.0 74456.0 76783.6 80466.0 81429.0 81587.6 536.8 547.6 552.2 552.4 555.4 11190.9 11464.1 11589.4 11596.3 11654.8 33970.3 34522.8 35044.3 35177.7 35657.1 46654.5 47254.5 47561.8 48446.0 48882.5 6893.1 6920.8 6948.0 6959.4 7001.3 12459.6 12528.3 12655.4 12810.9 12870.0 9384.8 10624.3 10783.1 10853.9 11015.9 10310.2 10551.3 10699.9 10844.6 10932.2 10726.0 10727.8 10974.1 10985.6 10988.8 6723.4 6770.5 6831.6 6851.7 6860.6 10573.5 10633.9 10693.3 10698.1 10789.8 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5221.34 Median:6334.93 stddev:2648.08 (41.8%) stddev-sans-first:2639.07 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: Values: 1297.4 1703.4 1716.7 1739.7 1767.6 7166.0 7482.5 7562.8 7584.9 7711.4 7528.6 7543.1 7556.7 7602.4 7604.9 2171.0 2198.2 2203.4 2204.5 2204.9 2229.4 2253.8 2257.2 2257.9 2260.9 2201.1 2244.9 2266.5 2268.8 2278.9 2220.1 2244.3 2256.7 2268.9 2273.2 2189.0 2243.8 2257.3 2270.6 2275.7 7457.7 7557.7 7602.4 7609.6 7609.8 4548.5 4582.6 4608.1 4613.7 4649.5 3007.7 3016.1 3072.8 3102.7 3102.8 1010.8 1022.4 1032.3 1042.6 1068.6 10199.6 10239.1 10304.5 10318.7 10374.6 3923.2 4019.8 4055.9 4060.1 4100.0 7944.0 7949.5 7950.3 7961.7 7972.3 4533.9 4536.1 4565.1 4570.0 4570.4 4192.2 4193.9 4199.5 4203.8 4206.7 3372.7 3391.6 3405.1 3405.8 3421.1 1059.1 1069.5 1069.7 1072.2 1080.3 1058.2 1070.3 1116.5 1228.6 1348.7 6900.4 6981.1 6986.1 7018.1 7311.2 925.6 934.8 942.8 943.2 944.9 5493.1 5496.7 5534.8 5547.4 5556.4 8811.6 8823.5 8894.6 9070.5 9083.8 8790.6 8838.0 8925.2 8933.5 8946.7 6711.7 6750.9 6777.1 6849.3 6860.3 6643.3 6671.4 6681.8 6717.1 6717.3 7325.9 7493.4 7500.1 7548.3 7622.5 7536.2 7652.4 7685.3 7720.8 7736.9 6493.5 6519.6 6554.5 6563.5 6654.4 7207.9 7419.1 7437.5 7437.7 7453.6 6576.8 6933.3 6992.9 7001.5 7133.4 6753.1 6782.5 6786.0 6832.8 6927.5 6738.4 6856.9 6910.9 6911.0 6930.8 6165.6 6193.4 6211.5 6219.7 6223.7 6281.6 6388.2 6391.3 6451.8 6466.2 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5579.60 Median:6421.21 stddev:1732.48 (27.0%) stddev-sans-first:1691.65 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: Values: 313.7 319.3 321.3 323.8 325.3 6197.7 6305.0 6385.1 6410.8 6526.0 6275.7 6308.1 6315.1 6331.9 6339.1 4522.2 4569.4 4649.6 4800.8 4807.1 4723.6 4747.8 4770.1 4774.4 4777.8 4676.7 4701.7 4703.4 4711.2 4711.8 4696.5 4705.9 4716.3 4723.1 4725.8 4000.4 4055.9 4062.5 4069.6 4075.9 5930.7 5964.8 5967.7 5973.8 5979.1 5477.5 5479.1 5482.5 5484.7 5496.2 6471.0 6595.5 6620.2 6662.6 6669.0 4472.4 4475.6 4478.8 4483.8 4496.7 6907.6 6971.9 6985.1 6999.9 7004.1 4536.5 4561.2 4593.2 4602.0 4606.4 4526.4 4648.3 4658.0 4659.3 4661.9 5414.2 5415.5 5429.9 5447.6 5450.5 6679.1 6697.4 6714.0 6732.1 6732.4 1641.7 1644.0 1651.9 1660.9 1662.9 1048.3 1054.7 1060.1 1064.8 1084.7 6348.3 6413.9 6445.8 6463.2 6536.6 6954.8 6982.6 6984.3 6991.4 6995.5 6469.5 6480.1 6503.2 6510.5 6516.2 3735.1 3777.8 3785.8 3801.0 3810.2 7225.1 7230.2 7281.6 7282.7 7283.3 7158.2 7169.9 7175.7 7178.7 7190.3 7082.5 7190.7 7230.7 7280.8 7282.1 6949.8 6963.8 6983.6 6984.2 6984.7 6827.1 6876.8 6881.3 6895.2 6905.4 7142.8 7215.6 7283.4 7298.8 7306.9 4621.4 4689.5 4691.5 4692.4 4694.5 6909.1 6935.9 6942.5 6946.1 6947.1 6650.4 6765.9 6769.1 6769.2 6771.0 6717.2 6747.4 6749.2 6760.3 6761.7 6513.8 6619.4 6635.2 6644.8 6657.2 6466.8 6630.5 6733.7 6780.7 6787.6 6428.6 6552.0 6568.0 6573.3 6609.7 10:07:34 INFO - 2015-11-18 10:07:34,513 DEBUG : BROWSER_OUTPUT: 10:07:34 INFO - 2015-11-18 10:07:34,515 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13322.55 Median:47120.20 stddev:15718.49 (33.4%) stddev-sans-first:15734.09 10:07:34 INFO - 2015-11-18 10:07:34,515 DEBUG : BROWSER_OUTPUT: Values: 718.8 753.7 777.3 795.8 842.4 13606.6 13656.3 13887.3 13908.9 13916.6 12979.7 13058.7 13067.8 13076.2 13078.3 743.7 744.3 754.7 756.4 771.6 717.5 719.5 725.4 726.6 737.7 748.4 799.4 824.3 831.1 832.5 812.4 814.6 817.4 821.7 823.3 542.0 544.7 551.3 554.5 577.8 13713.1 13729.3 13788.5 13792.9 13795.2 9919.2 10045.7 10110.2 10149.4 10453.2 9807.7 9842.9 9953.5 10042.7 10275.9 3174.7 3262.0 3273.3 3276.5 3353.0 45998.3 46028.8 46075.8 46084.0 46589.8 7348.4 7572.2 7593.9 7709.5 7769.4 11930.9 11961.6 11969.9 11993.3 12005.9 6405.3 6527.7 6537.4 6675.2 6729.6 4315.7 4328.0 4378.0 4393.9 4498.7 2716.1 2721.7 2726.2 2730.1 2732.6 414.4 418.2 422.0 428.8 431.5 441.8 455.4 484.4 577.5 658.6 8438.5 8711.7 8750.6 8799.2 8800.1 484.3 569.9 598.1 600.3 617.2 3887.7 3945.2 4036.2 4176.6 4246.2 53154.5 53680.7 53740.0 53780.5 53983.0 49054.3 50468.0 51047.7 51300.0 51724.0 47603.9 48002.5 48012.0 48696.0 48696.3 8751.2 8840.9 8853.2 8973.2 9015.1 32647.0 33744.0 33761.2 34515.7 36622.6 44315.3 46101.3 46133.5 46177.5 46636.5 8355.2 8362.3 8372.1 8427.6 8509.7 11571.8 11885.4 12209.2 12362.4 12678.4 11734.4 11806.2 12003.9 12406.6 12434.5 11713.9 11867.3 12008.2 12475.6 12506.9 11308.7 11516.1 11682.9 11748.9 12145.1 9454.7 9552.4 9757.2 9882.1 9899.2 8927.2 9155.5 10016.1 10165.9 10504.2 10:07:34 INFO - 2015-11-18 10:07:34,515 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:07:34 INFO - 2015-11-18 10:07:34,515 DEBUG : BROWSER_OUTPUT: 10:07:35 INFO - 2015-11-18 10:07:35,221 INFO : Browser exited with error code: 0 10:07:35 INFO - 2015-11-18 10:07:35,238 INFO : Completed test dromaeo_css (00:18:31) 10:07:35 INFO - 2015-11-18 10:07:35,238 INFO : Starting test kraken 10:07:35 INFO - 2015-11-18 10:07:35,238 DEBUG : operating with platform_type : linux_ 10:07:35 INFO - 2015-11-18 10:07:35,238 INFO : Initialising browser for kraken test... 10:07:35 INFO - 2015-11-18 10:07:35,244 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFHk0I5/profile http://localhost:38298/getInfo.html 10:07:39 INFO - 2015-11-18 10:07:39,326 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:07:39 INFO - 2015-11-18 10:07:39,327 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:07:39 INFO - 2015-11-18 10:07:39,327 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:07:39 INFO - 2015-11-18 10:07:39,327 DEBUG : BROWSER_OUTPUT: __metrics 10:07:40 INFO - 2015-11-18 10:07:40,297 INFO : Browser initialized. 10:07:40 INFO - 2015-11-18 10:07:40,298 INFO : Running cycle 1/1 for kraken test... 10:07:40 INFO - 2015-11-18 10:07:40,298 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFHk0I5/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:07:52 INFO - 2015-11-18 10:07:52,053 DEBUG : BROWSER_OUTPUT: RSS: Main: 140058624 10:07:52 INFO - 2015-11-18 10:07:52,053 DEBUG : BROWSER_OUTPUT: 10:07:54 INFO - 2015-11-18 10:07:54,590 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38298/tests/kraken/driver.html?testName=audio-beat-detection) 10:07:55 INFO - 2015-11-18 10:07:55,197 DEBUG : BROWSER_OUTPUT: RSS: Main: 143101952 10:07:55 INFO - 2015-11-18 10:07:55,197 DEBUG : BROWSER_OUTPUT: 10:07:57 INFO - 2015-11-18 10:07:57,854 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38298/tests/kraken/driver.html?testName=audio-dft) 10:07:58 INFO - 2015-11-18 10:07:58,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 142393344 10:07:58 INFO - 2015-11-18 10:07:58,447 DEBUG : BROWSER_OUTPUT: 10:08:01 INFO - 2015-11-18 10:08:01,306 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38298/tests/kraken/driver.html?testName=audio-fft) 10:08:01 INFO - 2015-11-18 10:08:01,873 DEBUG : BROWSER_OUTPUT: RSS: Main: 137523200 10:08:01 INFO - 2015-11-18 10:08:01,873 DEBUG : BROWSER_OUTPUT: 10:08:04 INFO - 2015-11-18 10:08:04,079 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38298/tests/kraken/driver.html?testName=audio-oscillator) 10:08:04 INFO - 2015-11-18 10:08:04,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 137654272 10:08:04 INFO - 2015-11-18 10:08:04,682 DEBUG : BROWSER_OUTPUT: 10:08:06 INFO - 2015-11-18 10:08:06,868 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38298/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:07 INFO - 2015-11-18 10:08:07,450 DEBUG : BROWSER_OUTPUT: RSS: Main: 137752576 10:08:07 INFO - 2015-11-18 10:08:07,450 DEBUG : BROWSER_OUTPUT: 10:08:10 INFO - 2015-11-18 10:08:10,490 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38298/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:11 INFO - 2015-11-18 10:08:11,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 139104256 10:08:11 INFO - 2015-11-18 10:08:11,076 DEBUG : BROWSER_OUTPUT: 10:08:14 INFO - 2015-11-18 10:08:14,498 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38298/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:15 INFO - 2015-11-18 10:08:15,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 140169216 10:08:15 INFO - 2015-11-18 10:08:15,100 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2015-11-18 10:08:17,941 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38298/tests/kraken/driver.html?testName=json-parse-financial) 10:08:18 INFO - 2015-11-18 10:08:18,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 140017664 10:08:18 INFO - 2015-11-18 10:08:18,530 DEBUG : BROWSER_OUTPUT: 10:08:20 INFO - 2015-11-18 10:08:20,123 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38298/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:20 INFO - 2015-11-18 10:08:20,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 139378688 10:08:20 INFO - 2015-11-18 10:08:20,696 DEBUG : BROWSER_OUTPUT: 10:08:22 INFO - 2015-11-18 10:08:22,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:23 INFO - 2015-11-18 10:08:23,126 DEBUG : BROWSER_OUTPUT: RSS: Main: 139534336 10:08:23 INFO - 2015-11-18 10:08:23,127 DEBUG : BROWSER_OUTPUT: 10:08:25 INFO - 2015-11-18 10:08:25,252 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:25 INFO - 2015-11-18 10:08:25,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 139366400 10:08:25 INFO - 2015-11-18 10:08:25,845 DEBUG : BROWSER_OUTPUT: 10:08:28 INFO - 2015-11-18 10:08:28,327 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:28 INFO - 2015-11-18 10:08:28,920 DEBUG : BROWSER_OUTPUT: RSS: Main: 139243520 10:08:28 INFO - 2015-11-18 10:08:28,920 DEBUG : BROWSER_OUTPUT: 10:08:31 INFO - 2015-11-18 10:08:31,524 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:32 INFO - 2015-11-18 10:08:32,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 140214272 10:08:32 INFO - 2015-11-18 10:08:32,113 DEBUG : BROWSER_OUTPUT: 10:08:33 INFO - 2015-11-18 10:08:33,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38298/tests/kraken/driver.html?testName=ai-astar) 10:08:34 INFO - 2015-11-18 10:08:34,032 DEBUG : BROWSER_OUTPUT: RSS: Main: 140206080 10:08:34 INFO - 2015-11-18 10:08:34,032 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,033 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;106;112;111;115;130;111;111;112;111 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;128;154;158;126;155;157;125;161;126 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;162;162;162;163;163;165;164;170;161 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;73;75;71;79;73;152;72;73 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;140;91;90;91;138;91;91;131 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;111;110;109;110;110;110;110;110 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;147;149;146;147;146;147;146 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;97;96;97;96;97;96;96;96;96 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;66;64;64;63;63;63;63;64;61;62 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;70;69;71;69;68;68;69;69 10:08:34 INFO - 2015-11-18 10:08:34,034 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;93;86;91;94;93;85;86;88;86;86 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;121;120;125;122;132;121;128;128;127 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;151;154;149;154;150;150;157;156;141 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;67;67;67;69;68;67;68;68;66 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,717 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: __startTimestamp1447870114032__endTimestamp 10:08:34 INFO - 2015-11-18 10:08:34,035 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,038 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:08:34 INFO - 2015-11-18 10:08:34,039 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:08:34 INFO - 2015-11-18 10:08:34,039 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,039 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.00 Median:111.50 stddev:6.36 (5.7%) stddev-sans-first:6.70 10:08:34 INFO - 2015-11-18 10:08:34,039 DEBUG : BROWSER_OUTPUT: Values: 111.0 106.0 112.0 111.0 115.0 130.0 111.0 111.0 112.0 111.0 10:08:34 INFO - 2015-11-18 10:08:34,039 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,040 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.60 Median:155.50 stddev:15.92 (10.2%) stddev-sans-first:16.34 10:08:34 INFO - 2015-11-18 10:08:34,040 DEBUG : BROWSER_OUTPUT: Values: 156.0 128.0 154.0 158.0 126.0 155.0 157.0 125.0 161.0 126.0 10:08:34 INFO - 2015-11-18 10:08:34,040 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,040 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.40 Median:163.00 stddev:2.59 (1.6%) stddev-sans-first:2.70 10:08:34 INFO - 2015-11-18 10:08:34,040 DEBUG : BROWSER_OUTPUT: Values: 162.0 162.0 162.0 162.0 163.0 163.0 165.0 164.0 170.0 161.0 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.20 Median:73.00 stddev:24.98 (34.2%) stddev-sans-first:26.27 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 73.0 75.0 71.0 79.0 73.0 152.0 72.0 73.0 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.30 Median:90.50 stddev:22.22 (24.6%) stddev-sans-first:22.96 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 140.0 91.0 90.0 91.0 138.0 91.0 91.0 131.0 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,041 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 111.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 147.0 149.0 146.0 147.0 146.0 147.0 146.0 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: Values: 97.0 97.0 96.0 97.0 96.0 97.0 96.0 96.0 96.0 96.0 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.50 stddev:1.34 (2.1%) stddev-sans-first:1.00 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: Values: 66.0 64.0 64.0 63.0 63.0 63.0 63.0 64.0 61.0 62.0 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 69.0 71.0 69.0 68.0 68.0 69.0 69.0 10:08:34 INFO - 2015-11-18 10:08:34,042 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:89.50 stddev:3.55 (4.0%) stddev-sans-first:3.43 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: Values: 93.0 86.0 91.0 94.0 93.0 85.0 86.0 88.0 86.0 86.0 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:126.00 stddev:3.95 (3.1%) stddev-sans-first:4.14 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: Values: 123.0 121.0 120.0 125.0 122.0 132.0 121.0 128.0 128.0 127.0 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:152.50 stddev:4.95 (3.2%) stddev-sans-first:4.80 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: Values: 145.0 151.0 154.0 149.0 154.0 150.0 150.0 157.0 156.0 141.0 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:0.88 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: Values: 69.0 67.0 67.0 67.0 69.0 68.0 67.0 68.0 68.0 66.0 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:08:34 INFO - 2015-11-18 10:08:34,043 DEBUG : BROWSER_OUTPUT: 10:08:35 INFO - 2015-11-18 10:08:35,053 INFO : Browser exited with error code: 0 10:08:35 INFO - 2015-11-18 10:08:35,102 INFO : Completed test kraken (00:00:59) 10:08:35 INFO - 2015-11-18 10:08:35,102 INFO : Starting test v8_7 10:08:35 INFO - 2015-11-18 10:08:35,102 DEBUG : operating with platform_type : linux_ 10:08:35 INFO - 2015-11-18 10:08:35,102 INFO : Initialising browser for v8_7 test... 10:08:35 INFO - 2015-11-18 10:08:35,108 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYUxS5Z/profile http://localhost:38298/getInfo.html 10:08:39 INFO - 2015-11-18 10:08:39,069 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:08:39 INFO - 2015-11-18 10:08:39,069 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:08:39 INFO - 2015-11-18 10:08:39,069 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:08:39 INFO - 2015-11-18 10:08:39,070 DEBUG : BROWSER_OUTPUT: __metrics 10:08:40 INFO - 2015-11-18 10:08:40,089 INFO : Browser initialized. 10:08:40 INFO - 2015-11-18 10:08:40,090 INFO : Running cycle 1/1 for v8_7 test... 10:08:40 INFO - 2015-11-18 10:08:40,090 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYUxS5Z/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:08:51 INFO - 2015-11-18 10:08:51,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 141860864 10:08:51 INFO - 2015-11-18 10:08:51,865 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,312 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38298/tests/v8_7/run.html (next: http://localhost:38298/tests/v8_7/run.html) 10:09:13 INFO - 2015-11-18 10:09:13,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 137351168 10:09:13 INFO - 2015-11-18 10:09:13,562 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,562 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:09:13 INFO - 2015-11-18 10:09:13,562 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:09:13 INFO - 2015-11-18 10:09:13,563 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:09:13 INFO - 2015-11-18 10:09:13,563 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:09:13 INFO - 2015-11-18 10:09:13,563 DEBUG : BROWSER_OUTPUT: |0;Richards;162.3640201331385 10:09:13 INFO - 2015-11-18 10:09:13,563 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.98642095053347 10:09:13 INFO - 2015-11-18 10:09:13,563 DEBUG : BROWSER_OUTPUT: |2;Encrypt;285.9593937660852 10:09:13 INFO - 2015-11-18 10:09:13,563 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5081.218274111675 10:09:13 INFO - 2015-11-18 10:09:13,564 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1070.6638115631692 10:09:13 INFO - 2015-11-18 10:09:13,564 DEBUG : BROWSER_OUTPUT: |5;Earley;718.9072609633357 10:09:13 INFO - 2015-11-18 10:09:13,564 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 10:09:13 INFO - 2015-11-18 10:09:13,564 DEBUG : BROWSER_OUTPUT: |7;RegExp;25425 10:09:13 INFO - 2015-11-18 10:09:13,564 DEBUG : BROWSER_OUTPUT: |8;Splay;624.9221183800623 10:09:13 INFO - 2015-11-18 10:09:13,566 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4859.223300970873 10:09:13 INFO - 2015-11-18 10:09:13,566 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:09:13 INFO - 2015-11-18 10:09:13,566 DEBUG : BROWSER_OUTPUT: __startTimestamp1447870153561__endTimestamp 10:09:13 INFO - 2015-11-18 10:09:13,566 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,566 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:09:13 INFO - 2015-11-18 10:09:13,566 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.36 Median:162.36 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: Values: 162.4 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.99 Median:193.99 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: Values: 194.0 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,567 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:285.96 Median:285.96 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: Values: 286.0 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5081.22 Median:5081.22 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: Values: 5081.2 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1070.66 Median:1070.66 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,568 DEBUG : BROWSER_OUTPUT: Values: 1070.7 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:718.91 Median:718.91 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: Values: 718.9 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: Values: 12219.5 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25425.00 Median:25425.00 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,569 DEBUG : BROWSER_OUTPUT: Values: 25425.0 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:624.92 Median:624.92 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: Values: 624.9 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4859.22 Median:4859.22 stddev:0.00 (0.0%) 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: Values: 4859.2 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:09:13 INFO - 2015-11-18 10:09:13,570 DEBUG : BROWSER_OUTPUT: 10:09:14 INFO - 2015-11-18 10:09:14,426 INFO : Browser exited with error code: 0 10:09:14 INFO - 2015-11-18 10:09:14,437 INFO : Completed test v8_7 (00:00:39) 10:09:14 INFO - 2015-11-18 10:09:14,570 INFO : Completed test suite (00:20:10) 10:09:14 INFO - 2015-11-18 10:09:14,571 DEBUG : Working with test: dromaeo_css 10:09:14 INFO - 2015-11-18 10:09:14,571 DEBUG : Generating results file: dromaeo_css 10:09:14 INFO - 2015-11-18 10:09:14,572 DEBUG : Working with test: kraken 10:09:14 INFO - 2015-11-18 10:09:14,572 DEBUG : Generating results file: kraken 10:09:14 INFO - 2015-11-18 10:09:14,572 INFO : javascript benchmark 10:09:14 INFO - 2015-11-18 10:09:14,572 DEBUG : Working with test: v8_7 10:09:14 INFO - 2015-11-18 10:09:14,573 DEBUG : Generating results file: v8_7 10:09:14 INFO - 2015-11-18 10:09:14,573 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:09:14 INFO - 2015-11-18 10:09:14,631 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 10:09:14 INFO - 2015-11-18 10:09:14,631 DEBUG : process_Request line: dromaeo_css 6645.09 graph.html#tests=[[72,1,43]] 10:09:14 INFO - 2015-11-18 10:09:14,631 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:09:14 INFO - 2015-11-18 10:09:14,669 DEBUG : process_Request line: kraken 1523.80 graph.html#tests=[[232,1,43]] 10:09:14 INFO - 2015-11-18 10:09:14,669 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:09:14 INFO - 2015-11-18 10:09:14,698 DEBUG : process_Request line: v8_7 21246.96 graph.html#tests=[[230,1,43]] 10:09:14 INFO - 2015-11-18 10:09:14,700 INFO : javascript benchmark 10:09:14 INFO - 2015-11-18 10:09:14,703 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3190.6968837960394, 3260.9510857337127, 3278.8196649184315, 3295.686909743575, 3305.1406634275095, 5284.128118860383, 5465.863353416166, 5491.3410390753115, 5499.917501237484, 5507.146392309189, 5334.466553344663, 5353.330833645794, 5376.489233522819, 5393.622446428752, 5444.564434845212, 4708.223143181388, 4941.925871111936, 5145.0, 5154.175331946889, 5157.896842063156, 4810.927836082462, 5071.391433028042, 5074.441811400743, 5083.974580127113, 5142.640015198937, 4849.739067842369, 4927.236278376839, 4997.775100120503, 5019.397672279313, 5023.472535383787, 4993.575546078588, 4997.90004199914, 5039.6220283478515, 5041.72270525121, 5058.140116180258, 4901.950980490185, 5009.0482808266315, 5014.348134742497, 5043.87390315245, 5064.80558325025, 5182.4299327073995, 5213.4004589472315, 5221.086309895753, 5284.048871203182, 5288.286081379003, 5830.3586605473365, 5851.122331650244, 5861.2087368205175, 5869.354371019184, 5888.0, 5748.7125643717645, 5861.824145275648, 5880.852978675524, 5887.058070708709, 5912.201852749867, 5466.562674985991, 5495.6153069285165, 5498.80754173602, 5509.862253443656, 5555.583331250173, 5987.880242395127, 6006.219191505076, 6012.338642749294, 6014.338422773491, 6028.125921741411, 5677.460641239076, 5778.422157784188, 5781.364049954585, 5807.390224026501, 5815.505682017076, 5903.675297858573, 5917.940820591825, 5918.023526118142, 5939.227900372924, 5959.851003724942, 5812.94187058124, 5858.970705146532, 5884.382139875252, 5888.676122813201, 5889.4993925515955, 5633.943660563424, 5720.771169153188, 5732.684702341411, 5809.50619197373, 5880.26496687914, 4451.888702782457, 4475.731456112644, 4485.304777759453, 4492.213862574036, 4518.751468669189, 3961.900952476211, 3982.104026594051, 4002.479677641946, 4004.4994375703036, 4051.6961227908023, 5402.0, 5508.394076651565, 5511.53151982086, 5606.355269144101, 5608.383077861432, 5850.824475265749, 5867.090600956859, 5877.647341159544, 5902.940970590325, 5938.257717785277, 5487.75305111271, 5492.5331346835965, 5512.779488820483, 5526.50261476469, 5527.060399732055, 4907.214845624683, 4935.087008903364, 4984.37695288089, 4998.500149984972, 5047.394312682428, 6021.7591296347655, 6075.969620151871, 6077.270727512727, 6092.939070609325, 6098.72555734993, 5680.062789639664, 5872.265966754156, 5877.353491115889, 5883.294004719461, 5892.381299963442, 5799.768009279582, 6006.039033754579, 6009.06859436788, 6086.786962456293, 6125.877482450237, 5712.457316554921, 5824.825255242349, 5844.240248767633, 5863.325717542537, 5864.706764661664, 5685.715714214355, 5802.94197058024, 5888.17565540816, 5889.5582831286965, 5899.528037756883, 5609.579281553818, 5631.8592035199445, 5652.943470565242, 5684.687342196054, 5751.367349591458, 5568.248286481273, 5694.971525142348, 5724.3703192647945, 5763.8559036024435, 5792.507636850914, 5418.864528386822, 5642.492175704207, 5647.29408823897, 5695.629784064023, 5705.942940570541, 5657.0, 5658.7736490541565, 5727.739897222605, 5825.359210486928, 5865.413458654271, 5672.14917762339, 5682.374938756816, 5705.914411283917, 5709.885802284014, 5732.799352022659], "unit": "ms", "name": "dojo.html", "value": 5395.347904076752}, {"lowerIsBetter": false, "replicates": [563.6871536297356, 587.1691556447624, 592.869568694887, 596.7404179182056, 609.4636719686677, 38973.80513097434, 39476.80261598688, 39717.60282397175, 39729.60270397295, 39768.00579985498, 41902.58097419025, 42103.57896421035, 42369.15261694764, 42398.0, 42418.787906060425, 8726.47641141532, 8833.337499687517, 8856.158664926821, 8868.068852770462, 8908.643654253821, 8676.652933882637, 8737.21365077143, 8764.649414023432, 8802.735917922473, 8809.559522023874, 8747.868781968276, 8832.8675069874, 8870.157335053193, 8872.20150186483, 8873.378863479558, 8634.481931084156, 8637.0499245083, 8689.0, 8690.913090869073, 8714.607842647096, 8613.353998450142, 8674.653013879437, 8748.78128046804, 8749.03760586335, 8751.78120546985, 39325.60674393248, 40131.19737605232, 40143.799281003405, 40221.195576088314, 40224.19551608981, 3230.5800245968107, 3502.912427189315, 3515.666011728907, 3839.827207775657, 3958.3580730712742, 29005.32868501158, 29927.551086733718, 29988.70011299881, 30181.09456716302, 30931.536026959613, 3477.3393055319407, 3503.7722548034294, 3542.4863394807867, 3553.8272370118057, 3590.7845529268066, 257172.0, 258266.0, 258882.0, 258975.0, 260072.6996365025, 41300.0, 57476.0, 57886.0, 57895.421045789844, 57991.71004144952, 42894.78552607217, 43182.92042698958, 43376.78311608485, 43420.3486947696, 43439.0, 13262.403191856389, 13294.734105317839, 13310.267935263653, 13327.600171994856, 13352.6661833453, 10523.158147348193, 10528.263021588415, 10536.736581585521, 10545.050945414796, 10572.52423640938, 14269.215193164475, 14308.856911430752, 14316.0, 14387.856121438652, 14428.927855360866, 5748.913766293509, 5883.410939467222, 5890.8821823563285, 5952.255968003999, 5977.057456326711, 868.8349213649512, 972.2465089555651, 978.3249557805092, 1032.695354870315, 1102.9172812039128, 27998.56606554287, 28171.154865354074, 28192.29519261994, 28637.85681071623, 28876.0, 975.8145952269047, 1005.7435353984687, 1024.6464969585481, 1071.9816174633972, 1081.5187241677536, 14915.328810203568, 16280.918595406947, 16769.580760481087, 16925.576860578338, 17130.828691713174, 390794.0, 391101.0, 393509.0, 394508.0, 399008.0, 53854.0, 53945.730271348395, 54042.729786350814, 54299.0, 54337.1849422259, 1314.750197462479, 1350.7906274527463, 1355.5323413422354, 1359.2931675528866, 1380.8412032616377, 29177.416451671274, 30378.392432151675, 32267.838660806545, 33405.83297083499, 33573.49639755455, 47607.28589071236, 48304.516954830004, 48342.516574833804, 48377.27434088562, 48390.0, 294228.0, 299287.0, 299663.0, 299955.0, 300698.0, 40494.79752601218, 45039.774801127096, 45265.0, 45368.31947520856, 45549.77225113853, 46527.76736116433, 47013.76493117512, 47204.0, 47333.7633311845, 47412.2888156671, 38463.807680961414, 38827.6117238824, 39952.800235998635, 40019.0, 40477.0, 39749.602503975744, 40326.19347612972, 42196.0, 42632.78753993585, 44059.33910991402, 38159.42760858645, 38322.233555329294, 38337.0, 38759.41860872033, 38789.22421551609, 34446.655533445344, 35281.47077793886, 35329.82335088308, 35576.110597235274, 36418.0, 26832.463350733266, 27033.566978971987, 27187.320316992234, 27192.72807271902, 27246.182614521596], "unit": "ms", "name": "ext.html", "value": 18544.41640847161}, {"lowerIsBetter": false, "replicates": [347.22568671861745, 349.9107727529481, 351.4359453077811, 351.8574977134261, 353.840771652756, 12125.393730313494, 12527.0, 16833.915830420847, 17732.82267177328, 19157.616847663056, 11527.481263343148, 11570.132240082006, 11596.072314214864, 11626.593069242579, 11902.583409580666, 4518.390017347665, 4606.0327331260305, 4606.5393460654, 4610.101030299093, 4611.976940115312, 4451.755153466558, 4561.361409402686, 4562.954370456286, 4563.995920897397, 4569.063342014895, 4473.776311184444, 4522.479914809806, 4672.2057250267535, 4787.305840653104, 4808.61531077513, 4745.620350371962, 4755.50067242938, 4774.021325628238, 4774.1645212087915, 4784.808607655689, 4173.457450531442, 4291.892702682427, 4303.569643035703, 4305.806238719265, 4310.741355518679, 12924.54764083262, 12964.157329773534, 13229.140105893086, 13457.596272111852, 13495.66260843477, 1819.645169192008, 1930.9903450482657, 2011.6379051770675, 2038.6330460517102, 2042.8365730741505, 9765.755856103655, 9872.802543949152, 9946.452945088018, 9967.302288839785, 9985.251106167067, 1618.207078531523, 1705.0707364486384, 1731.333436626895, 1732.8267173282698, 1769.7699299091164, 58680.0, 60355.69822150861, 60655.39344606541, 61413.6929315355, 61747.0, 6697.464202863759, 6712.328767123347, 6835.58986460814, 6837.290677330668, 6858.485613579001, 8145.633446494922, 8218.671253149807, 8220.671173153007, 8234.917650823534, 8235.258826705625, 5191.650170955579, 5253.527182553588, 5364.061289274361, 5385.353757549119, 5393.298871146804, 5404.216388623706, 5425.83722488326, 5455.18172274162, 5465.906818636209, 5467.917981230285, 3169.904902852918, 3206.246532064963, 3219.3400352927592, 3231.369882872817, 3237.4496335623003, 1136.8010598145293, 1138.9886101139048, 1141.0814294493014, 1147.713071732067, 1163.1102206811795, 418.4769038701623, 418.5563302898937, 419.264191344189, 433.19425867885707, 436.76633001344766, 10011.199104071657, 10393.324433911923, 10431.321964072311, 10431.478426078666, 10523.684289471328, 408.15104582468393, 422.81396185678204, 430.2814300118809, 433.17696376884174, 438.9275769497997, 5604.243427137284, 5769.105788602689, 5778.913316300259, 5865.178874957509, 5868.2664666916635, 500568.0, 501229.0, 504787.0, 507682.0, 518157.0, 74456.0, 76783.61608191923, 80466.0, 81429.0, 81587.59206203932, 536.8013834881119, 547.5756288876073, 552.1634723394061, 552.4447929830494, 555.3752028967411, 11190.94404527972, 11464.140189485988, 11589.420528973686, 11596.304221746723, 11654.76690466169, 33970.32059358848, 34522.82738586291, 35044.29911401809, 35177.65812247832, 35657.1085722859, 46654.53345466502, 47254.527454725016, 47561.76219118882, 48446.0, 48882.5111748878, 6893.103896493424, 6920.757773477904, 6948.0272761812375, 6959.408450281783, 7001.299870012958, 12459.626211213677, 12528.310942898228, 12655.367231638196, 12810.935945320214, 12870.034747393796, 9384.812303754023, 10624.309419887682, 10783.083437907866, 10853.945730271298, 11015.944920275348, 10310.175185985254, 10551.261411701107, 10699.94650026776, 10844.620438284623, 10932.234743567873, 10726.034656880918, 10727.839082413926, 10974.067204287725, 10985.61550345734, 10988.780224395627, 6723.428508576826, 6770.45836333102, 6831.590104593542, 6851.725930962905, 6860.622665753432, 10573.52419141156, 10633.946830266108, 10693.304935179189, 10698.090662293793, 10789.838152427563], "unit": "ms", "name": "jquery.html", "value": 6752.010631930941}, {"lowerIsBetter": false, "replicates": [1297.416162726773, 1703.3782669325697, 1716.7167417376133, 1739.6694628020675, 1767.5669460982058, 7166.0, 7482.513636613617, 7562.848743025136, 7584.924150758491, 7711.4216433767415, 7528.623568821565, 7543.132539757929, 7556.659950302236, 7602.429817763662, 7604.88592671109, 2171.0447403142643, 2198.230619283254, 2203.43812327857, 2204.5370472200775, 2204.911803527857, 2229.4314949687805, 2253.763354847734, 2257.1874125314876, 2257.8645281283093, 2260.886955652219, 2201.0535469748, 2244.9438764030865, 2266.478709896725, 2268.7844654757773, 2278.943026424336, 2220.0564759977105, 2244.3267019893965, 2256.729192496911, 2268.931932042033, 2273.193016479151, 2188.9671654925187, 2243.775622437743, 2257.3115199864037, 2270.648049552322, 2275.7041584594062, 7457.664405101784, 7557.659905304219, 7602.429817763635, 7609.619519024027, 7609.847803043908, 4548.476925153584, 4582.587567118942, 4608.1244563533155, 4613.746243956581, 4649.465311489154, 3007.7142671446177, 3016.065019843855, 3072.830994295313, 3102.720755132049, 3102.7672924530752, 1010.8483727440869, 1022.3865680591659, 1032.3083534032216, 1042.6194439029775, 1068.5779117248644, 10199.592016319339, 10239.078482936571, 10304.484775761257, 10318.690439286833, 10374.63688770897, 3923.156521347924, 4019.8191081401405, 4055.9391609125887, 4060.0661847774586, 4100.01599616096, 7943.9602801986775, 7949.523028618301, 7950.284474397332, 7961.72133975308, 7972.322352600094, 4533.886652833639, 4536.070105628338, 4565.0641618468135, 4569.977150114228, 4570.405847239904, 4192.161567686475, 4193.874183774492, 4199.517055538591, 4203.76879271643, 4206.705530612889, 3372.696457318853, 3391.593008838955, 3405.0636075079055, 3405.8467368968454, 3421.0934102462866, 1059.1209296284067, 1069.4599227390274, 1069.6737495063924, 1072.1958531101673, 1080.3409919948824, 1058.227493929436, 1070.2936062198912, 1116.4696769034806, 1228.5577192210797, 1348.689801345696, 6900.447964162855, 6981.127359080115, 6986.126734158231, 7018.052562903943, 7311.195768465464, 925.6251218256617, 934.780326623243, 942.7784470649392, 943.1747221181466, 944.9055094490496, 5493.093639549509, 5496.725163741797, 5534.806281780118, 5547.417521160301, 5556.444355564411, 8811.559422028873, 8823.514706691065, 8894.59974301158, 9070.455772653662, 9083.81832363349, 8790.560471976376, 8838.0, 8925.152110549487, 8933.508657023796, 8946.731598051938, 6711.731530738716, 6750.932490675129, 6777.085093512412, 6849.280825513349, 6860.279670634612, 6643.30245324234, 6671.39957403836, 6681.832954176184, 6717.093192418961, 6717.328267173243, 7325.890111648223, 7493.4379921505015, 7500.13748418917, 7548.320651141424, 7622.504537205064, 7536.171021187774, 7652.426068044808, 7685.308322251024, 7720.8455830884195, 7736.949708327185, 6493.480521558358, 6519.576227545194, 6554.4756419487285, 6563.540552161303, 6654.401103900672, 7207.9279207207255, 7419.146798118286, 7437.516561423707, 7437.665305061393, 7453.590052547162, 6576.80269591913, 6933.306669333027, 6992.895106573507, 7001.541751628948, 7133.3936615388275, 6753.054572359972, 6782.491313151632, 6785.9660701696175, 6832.760853370109, 6927.480438967198, 6738.35985581369, 6856.862862742618, 6910.8617827644175, 6911.032455456139, 6930.792076237721, 6165.599236049642, 6193.38066193377, 6211.472024877936, 6219.657918814509, 6223.688815559294, 6281.623102613858, 6388.233411990591, 6391.296957334783, 6451.806445806634, 6466.159399278064], "unit": "ms", "name": "mootools.html", "value": 4316.842266731343}, {"lowerIsBetter": false, "replicates": [313.7317593457594, 319.33418821756635, 321.34606076634026, 323.80733463589166, 325.3135883286267, 6197.721102550385, 6305.0, 6385.138006369145, 6410.839729006777, 6526.0, 6275.7489700411925, 6308.116863639094, 6315.147455093561, 6331.936680633204, 6339.112524246598, 4522.163399771053, 4569.428821397325, 4649.558291962258, 4800.807967681298, 4807.062622788558, 4723.645726570521, 4747.786349614275, 4770.0698363819065, 4774.379330687023, 4777.832775852846, 4676.696014759047, 4701.670883038189, 4703.388559487262, 4711.222648263032, 4711.764411779414, 4696.53034696531, 4705.905881882378, 4716.339712440261, 4723.07899959508, 4725.787339569728, 4000.4199391088127, 4055.918881622351, 4062.5124985001694, 4069.633732964018, 4075.9184816303805, 5930.733117009745, 5964.761409543613, 5967.731452084667, 5973.790917317917, 5979.073243647242, 5477.534409575191, 5479.123340265538, 5482.451754824526, 5484.670919744828, 5496.203050557647, 6470.967645161792, 6595.505337099688, 6620.238672552622, 6662.633555154464, 6669.0, 4472.418585583853, 4475.641948644101, 4478.776061196927, 4483.820647174109, 4496.7077139985795, 6907.620080895549, 6971.8605627887155, 6985.05701730265, 6999.89500157493, 7004.054452648878, 4536.5236650151255, 4561.156186105514, 4593.173228818845, 4601.953980460153, 4606.424196975378, 4526.411566496335, 4648.2562789953445, 4658.0, 4659.277811939155, 4661.906761864744, 5414.18787181918, 5415.48552887475, 5429.891402171935, 5447.618666693294, 5450.454954504517, 6679.064930909676, 6697.430718388894, 6713.966430167916, 6732.091167692299, 6732.360425759545, 1641.65525239701, 1643.9753403698953, 1651.9091449970367, 1660.9335626575044, 1662.9085400302858, 1048.2819268800895, 1054.7099547624464, 1060.0618452669444, 1064.834950582661, 1084.7233955341496, 6348.269948955837, 6413.871722565523, 6445.774397896051, 6463.224413070461, 6536.640484773288, 6954.79135625932, 6982.615956122462, 6984.266652001466, 6991.370776630128, 6995.475339349569, 6469.482441404676, 6480.092787009822, 6503.154589903283, 6510.544261901716, 6516.152900123048, 3735.1409175889153, 3777.773333599993, 3785.8107094645707, 3801.0, 3810.2379524094736, 7225.132984041844, 7230.2046774854725, 7281.5995120267835, 7282.70869165238, 7283.344498995116, 7158.2483839197175, 7169.89245161323, 7175.677094530759, 7178.748743793943, 7190.280971902873, 7082.468814838908, 7190.712371505081, 7230.674619642129, 7280.781576552712, 7282.053333066668, 6949.7567585134275, 6963.79108626742, 6983.580985140908, 6984.161900571964, 6984.685689143899, 6827.146606674166, 6876.793696189123, 6881.277465866212, 6895.241523432318, 6905.447564194752, 7142.750003749844, 7215.567065976058, 7283.380912622486, 7298.817529561591, 7306.890396643948, 4621.353010578455, 4689.460712018162, 4691.530846915418, 4692.366530518337, 4694.483606803181, 6909.136357955255, 6935.895961560682, 6942.479314051366, 6946.131733533309, 6947.062146610143, 6650.434713049445, 6765.86468270622, 6769.120014398097, 6769.153855768029, 6771.0, 6717.227518835201, 6747.392734653905, 6749.156355455568, 6760.290169532325, 6761.729530818712, 6513.804585862432, 6619.4042536170045, 6635.170603674541, 6644.800655980328, 6657.167854018247, 6466.838329041811, 6630.53586248958, 6733.730650773914, 6780.72877084911, 6787.62668053242, 6428.55000149985, 6551.967240163769, 6568.0, 6573.3098024706715, 6609.702563384563], "unit": "ms", "name": "prototype.html", "value": 5000.161583566592}, {"lowerIsBetter": false, "replicates": [718.8382613911871, 753.6834529497611, 777.2848978939371, 795.7771823889314, 842.3808500751946, 13606.591802245935, 13656.317184140802, 13887.305634718261, 13908.860911390882, 13916.582502524927, 12979.740405191891, 13058.738825223514, 13067.803982940264, 13076.215427074381, 13078.346082695874, 743.71738739279, 744.3077937518106, 754.7132089805866, 756.4364548411442, 771.5756334016297, 717.4977515738977, 719.5358993449222, 725.3508110241331, 726.6439444672098, 737.7454778101546, 748.4087570819073, 799.448380617375, 824.3446460064263, 831.1314676163394, 832.5004997001781, 812.4272387966499, 814.5560669435142, 817.3502065857627, 821.6795449774593, 823.3331001888489, 541.9729013549306, 544.7031367904501, 551.3136145498839, 554.4870994330229, 577.7804434314934, 13713.108647937852, 13729.313534323244, 13788.517401890986, 13792.862071379359, 13795.172289662652, 9919.206463482977, 10045.748856278578, 10110.180167569835, 10149.441780702133, 10453.216008799294, 9807.660710106933, 9842.90157098427, 9953.452560109192, 10042.748931276703, 10275.948620256851, 3174.68253174683, 3261.951070733941, 3273.3289675616443, 3276.459384201604, 3352.9664703352896, 45998.31002534931, 46028.7698561505, 46075.76962115168, 46084.0, 46589.76705116487, 7348.412127029825, 7572.242775722384, 7593.924060759322, 7709.4603377763015, 7769.3784497240085, 11930.88069119315, 11961.58134465307, 11969.880301196876, 11993.34036627994, 12005.939970300093, 6405.29541750407, 6527.706253218522, 6537.378948999837, 6675.232348279913, 6729.5625784323865, 4315.719478233905, 4328.0, 4378.0, 4393.934090988638, 4498.6626003049905, 2716.1172618898936, 2721.6733991921096, 2726.209399274228, 2730.1400058981358, 2732.6037724529833, 414.376363572821, 418.2054097215254, 422.0482811260588, 428.80489377333237, 431.4865310280756, 441.7680717623209, 455.3829560944938, 484.41144010027983, 577.4860374266906, 658.6311665467354, 8438.493690378597, 8711.695090671796, 8750.562471876381, 8799.164079412445, 8800.119988001148, 484.3001862309, 569.9059655156852, 598.1327075740194, 600.2526854066715, 617.237711426388, 3887.654791294602, 3945.151792364655, 4036.233115708006, 4176.561461046607, 4246.171996460722, 53154.46845531573, 53680.73159634177, 53740.0, 53780.46219537833, 53983.0, 49054.26418603724, 50468.0, 51047.74476127596, 51299.97400051978, 51724.0, 47603.85782639789, 48002.519974799805, 48012.03975920462, 48696.0, 48696.269555956686, 8751.168638979287, 8840.911590884009, 8853.15894989975, 8973.237274831452, 9015.14356136166, 32647.0, 33744.0, 33761.155971099935, 34515.654843451244, 36622.63377366299, 44315.33526997162, 46101.30848037215, 46133.53866461293, 46177.53822461867, 46636.53363466322, 8355.248027677539, 8362.331013518782, 8372.07907130203, 8427.5786210688, 8509.744707658532, 11571.768564628828, 11885.405729713308, 12209.206401583868, 12362.3818809061, 12678.429470673658, 11734.354610496504, 11806.173567850168, 12003.939980300043, 12406.565770198, 12434.502619895104, 11713.88286117128, 11867.347295898808, 12008.159428840247, 12475.625731228078, 12506.874931250572, 11308.717282068013, 11516.078713702716, 11682.94158529202, 11748.941255293668, 12145.149839511503, 9454.716358509255, 9552.42685438876, 9757.170640495637, 9882.11061004513, 9899.1585715215, 8927.24118449939, 9155.542222888696, 10016.148627366463, 10165.932577079597, 10504.159667226757], "unit": "ms", "name": "yui.html", "value": 5904.098314538147}], "name": "dromaeo_css", "value": 6645.093691508987}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 106.0, 112.0, 111.0, 115.0, 130.0, 111.0, 111.0, 112.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 113.0}, {"lowerIsBetter": true, "replicates": [156.0, 128.0, 154.0, 158.0, 126.0, 155.0, 157.0, 125.0, 161.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.6}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 162.0, 162.0, 163.0, 163.0, 165.0, 164.0, 170.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 163.4}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 75.0, 71.0, 79.0, 73.0, 152.0, 72.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 81.2}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 140.0, 91.0, 90.0, 91.0, 138.0, 91.0, 91.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 104.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 111.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 147.0, 149.0, 146.0, 147.0, 146.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0, 64.0, 61.0, 62.0], "unit": "ms", "name": "json-parse-financial", "value": 63.3}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 70.0, 69.0, 71.0, 69.0, 68.0, 68.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [93.0, 86.0, 91.0, 94.0, 93.0, 85.0, 86.0, 88.0, 86.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 88.8}, {"lowerIsBetter": true, "replicates": [123.0, 121.0, 120.0, 125.0, 122.0, 132.0, 121.0, 128.0, 128.0, 127.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.7}, {"lowerIsBetter": true, "replicates": [145.0, 151.0, 154.0, 149.0, 154.0, 150.0, 150.0, 157.0, 156.0, 141.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.7}, {"lowerIsBetter": true, "replicates": [69.0, 67.0, 67.0, 67.0, 69.0, 68.0, 67.0, 68.0, 68.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.6}], "name": "kraken", "value": 1523.7999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.3640201331385], "unit": "score", "name": "Richards", "value": 217.42501799999994}, {"lowerIsBetter": false, "replicates": [193.98642095053347], "unit": "score", "name": "DeltaBlue", "value": 340.8382899999999}, {"lowerIsBetter": false, "replicates": [285.9593937660852], "unit": "score", "name": "Encrypt", "value": 930.8349569999998}, {"lowerIsBetter": false, "replicates": [5081.218274111675], "unit": "score", "name": "Decrypt", "value": 52.38527172827171}, {"lowerIsBetter": false, "replicates": [1070.6638115631692], "unit": "score", "name": "RayTrace", "value": 691.1497260000002}, {"lowerIsBetter": false, "replicates": [718.9072609633357], "unit": "score", "name": "Earley", "value": 927.0500329999999}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [25425.0], "unit": "score", "name": "RegExp", "value": 35.83028515240902}, {"lowerIsBetter": false, "replicates": [624.9221183800623], "unit": "score", "name": "Splay", "value": 130.40184945164506}, {"lowerIsBetter": false, "replicates": [4859.223300970873], "unit": "score", "name": "NavierStokes", "value": 305.39860139860156}], "name": "v8_7", "value": 21246.96368558527}]} 10:09:14 INFO - RETURN: dromaeo_css: 6645.09 10:09:14 INFO - RETURN: kraken: 1523.80 10:09:14 INFO - RETURN: v8_7: 21246.96 10:09:14 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21246.96"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1523.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6645.09"}}} 10:09:14 INFO - Return code: 0 10:09:14 INFO - # TBPL SUCCESS # 10:09:14 INFO - Running post-action listener: _resource_record_post_action 10:09:14 INFO - Running post-run listener: _resource_record_post_run 10:09:15 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 0; Write bytes: 513015808; Read time: 0; Write time: 415568 10:09:15 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 130510848; Read time: 0; Write time: 128372 10:09:15 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 0; Write bytes: 382504960; Read time: 0; Write time: 287196 10:09:15 INFO - Running post-run listener: _upload_blobber_files 10:09:15 INFO - Blob upload gear active. 10:09:15 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:09:15 INFO - Copying logs to upload dir... 10:09:15 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.745537 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2015-11-18 10:09:15.203828) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 10:09:15.207497) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009309 build_url: 'https://queue.taskcluster.net/v1/task/NnHNMXZRQZms_YwsM7bkeA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 10:09:15.255982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:09:15.256361) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1447868900.539293-1963535425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004654 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:09:15.306125) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 10:09:15.306464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 10:09:15.306779) ========= ========= Total master_lag: 0.23 =========