builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-068 starttime: 1450445317.58 results: success (0) buildid: 20151218030232 builduid: dd2c433550c748459b7231a0d77fc8ff revision: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.578736) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.579230) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.579521) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006472 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.614949) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.615371) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.695979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.696242) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004605 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.747138) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.747429) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.747798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.748160) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 05:28:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2015-12-18 05:28:39 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165165 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.937261) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.937768) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014701 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.993660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.994013) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66fb852962c0d5f6f5fe0604204da4f5d17763c9 --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 66fb852962c0d5f6f5fe0604204da4f5d17763c9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 05:28:39,603 truncating revision to first 12 chars 2015-12-18 05:28:39,603 Setting DEBUG logging. 2015-12-18 05:28:39,604 attempt 1/10 2015-12-18 05:28:39,604 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66fb852962c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 05:28:39,947 unpacking tar archive at: mozilla-central-66fb852962c0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650203 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.663370) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.663647) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.674419) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.674682) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.675072) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-12-18 05:28:38.675358) ========= /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=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:28:40 INFO - MultiFileLogger online at 20151218 05:28:40 in /builds/slave/test-pgo 05:28:40 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 05:28:40 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:28:40 INFO - {'append_to_log': False, 05:28:40 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:28:40 INFO - 'blob_upload_branch': 'Firefox', 05:28:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:28:40 INFO - 'branch': 'Firefox', 05:28:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:40 INFO - 'config_files': ('talos/linux_config.py',), 05:28:40 INFO - 'default_actions': ('clobber', 05:28:40 INFO - 'read-buildbot-config', 05:28:40 INFO - 'download-and-extract', 05:28:40 INFO - 'populate-webroot', 05:28:40 INFO - 'create-virtualenv', 05:28:40 INFO - 'install', 05:28:40 INFO - 'run-tests'), 05:28:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:40 INFO - 'download_minidump_stackwalk': True, 05:28:40 INFO - 'download_symbols': 'ondemand', 05:28:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:40 INFO - 'installer_path': 'installer.exe', 05:28:40 INFO - 'log_level': 'info', 05:28:40 INFO - 'log_name': 'talos', 05:28:40 INFO - 'log_to_console': True, 05:28:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:28:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:28:40 INFO - 'opt_config_files': (), 05:28:40 INFO - 'pip_index': False, 05:28:40 INFO - 'sps_profile': False, 05:28:40 INFO - 'sps_profile_interval': 0, 05:28:40 INFO - 'suite': 'dromaeojs-e10s', 05:28:40 INFO - 'system_bits': '64', 05:28:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:40 INFO - 'title': 'talos-linux64-ix-068', 05:28:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:40 INFO - 'use_talos_json': True, 05:28:40 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:28:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:40 INFO - 'work_dir': 'build'} 05:28:40 INFO - ##### 05:28:40 INFO - ##### Running clobber step. 05:28:40 INFO - ##### 05:28:40 INFO - Running pre-action listener: _resource_record_pre_action 05:28:40 INFO - Running main action method: clobber 05:28:40 INFO - rmtree: /builds/slave/test-pgo/build 05:28:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:28:40 INFO - Running post-action listener: _resource_record_post_action 05:28:40 INFO - ##### 05:28:40 INFO - ##### Running read-buildbot-config step. 05:28:40 INFO - ##### 05:28:40 INFO - Running pre-action listener: _resource_record_pre_action 05:28:40 INFO - Running main action method: read_buildbot_config 05:28:40 INFO - Using buildbot properties: 05:28:40 INFO - { 05:28:40 INFO - "project": "", 05:28:40 INFO - "product": "firefox", 05:28:40 INFO - "script_repo_revision": "production", 05:28:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:28:40 INFO - "repository": "", 05:28:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:28:40 INFO - "buildid": "20151218030232", 05:28:40 INFO - "stage_platform": "linux64-pgo", 05:28:40 INFO - "basedir": "/builds/slave/test-pgo", 05:28:40 INFO - "nightly_build": "True", 05:28:40 INFO - "buildnumber": 586, 05:28:40 INFO - "slavename": "talos-linux64-ix-068", 05:28:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:28:40 INFO - "platform": "ubuntu64_hw", 05:28:40 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:28:40 INFO - "branch": "mozilla-central", 05:28:40 INFO - "repo_path": "mozilla-central", 05:28:40 INFO - "revision": "66fb852962c0d5f6f5fe0604204da4f5d17763c9", 05:28:40 INFO - "pgo_build": "True", 05:28:40 INFO - "builduid": "dd2c433550c748459b7231a0d77fc8ff", 05:28:40 INFO - "slavebuilddir": "test" 05:28:40 INFO - } 05:28:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:28:40 INFO - Running post-action listener: _resource_record_post_action 05:28:40 INFO - ##### 05:28:40 INFO - ##### Running download-and-extract step. 05:28:40 INFO - ##### 05:28:40 INFO - Running pre-action listener: _resource_record_pre_action 05:28:40 INFO - Running main action method: download_and_extract 05:28:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:40 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:28:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json 05:28:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json 05:28:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:28:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:28:44 INFO - Downloaded 1302 bytes. 05:28:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:28:44 INFO - Using the following test package requirements: 05:28:44 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:44 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:28:44 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'jsshell-linux-x86_64.zip'], 05:28:44 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:28:44 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:44 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:28:44 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:28:44 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:28:44 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:44 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:28:44 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:44 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:47 INFO - Downloaded 23120197 bytes. 05:28:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:48 INFO - Return code: 0 05:28:48 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:28: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')]} 05:28:48 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:52 INFO - Downloaded 23120197 bytes. 05:28:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:54 INFO - Return code: 0 05:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:54 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:28:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:28:55 INFO - Downloaded 11132039 bytes. 05:28:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:28:55 INFO - Return code: 0 05:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:55 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:28:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:28:56 INFO - Downloaded 54644993 bytes. 05:28:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:56 INFO - mkdir: /builds/slave/test-pgo/properties 05:28:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:28:56 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:28:56 INFO - Contents: 05:28:56 INFO - build_url:https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:56 INFO - Running post-action listener: _resource_record_post_action 05:28:56 INFO - Running post-action listener: set_extra_try_arguments 05:28:56 INFO - ##### 05:28:56 INFO - ##### Running populate-webroot step. 05:28:56 INFO - ##### 05:28:56 INFO - Running pre-action listener: _resource_record_pre_action 05:28:56 INFO - Running main action method: populate_webroot 05:28:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:56 INFO - '--tppagecycles', 05:28:56 INFO - '7'], 05:28:56 INFO - 'tests': ['tsvgm']}}, 05:28:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:28:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:28:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:28:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'tests': ['damp', 'tps']}, 05:28:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['damp', 'tps']}, 05:28:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:28:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:56 INFO - 'other': {'tests': ['a11yr', 05:28:56 INFO - 'ts_paint', 05:28:56 INFO - 'tpaint', 05:28:56 INFO - 'sessionrestore', 05:28:56 INFO - 'sessionrestore_no_auto_restore']}, 05:28:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['a11yr', 05:28:56 INFO - 'ts_paint', 05:28:56 INFO - 'tpaint', 05:28:56 INFO - 'sessionrestore', 05:28:56 INFO - 'sessionrestore_no_auto_restore']}, 05:28:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['a11yr', 05:28:56 INFO - 'ts_paint', 05:28:56 INFO - 'tpaint', 05:28:56 INFO - 'sessionrestore', 05:28:56 INFO - 'sessionrestore_no_auto_restore']}, 05:28:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['a11yr', 05:28:56 INFO - 'ts_paint', 05:28:56 INFO - 'tpaint', 05:28:56 INFO - 'sessionrestore', 05:28:56 INFO - 'sessionrestore_no_auto_restore']}, 05:28:56 INFO - 'other_l64': {'tests': ['a11yr', 05:28:56 INFO - 'ts_paint', 05:28:56 INFO - 'tpaint', 05:28:56 INFO - 'sessionrestore', 05:28:56 INFO - 'sessionrestore_no_auto_restore']}, 05:28:56 INFO - 'other_nol64': {'tests': ['a11yr', 05:28:56 INFO - 'ts_paint', 05:28:56 INFO - 'tpaint', 05:28:56 INFO - 'sessionrestore', 05:28:56 INFO - 'sessionrestore_no_auto_restore']}, 05:28:56 INFO - 'svgr': {'tests': ['tsvgx', 05:28:56 INFO - 'tsvgr_opacity', 05:28:56 INFO - 'tart', 05:28:56 INFO - 'tscrollx', 05:28:56 INFO - 'cart']}, 05:28:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['tsvgx', 05:28:56 INFO - 'tsvgr_opacity', 05:28:56 INFO - 'tart', 05:28:56 INFO - 'tscrollx', 05:28:56 INFO - 'cart']}, 05:28:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'tests': ['tp5o']}, 05:28:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'talos_options': ['--e10s'], 05:28:56 INFO - 'tests': ['tp5o']}, 05:28:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'talos_options': ['--xperf_path', 05:28:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:56 INFO - 'tests': ['tp5n']}, 05:28:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:56 INFO - 'talos_options': ['--e10s', 05:28:56 INFO - '--xperf_path', 05:28:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:56 INFO - 'tests': ['tp5n']}}, 05:28:56 INFO - 'talos.zip': {'path': '', 05:28:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:56 INFO - Running post-action listener: _resource_record_post_action 05:28:56 INFO - ##### 05:28:56 INFO - ##### Running create-virtualenv step. 05:28:56 INFO - ##### 05:28:56 INFO - Running pre-action listener: _resource_record_pre_action 05:28:56 INFO - Running main action method: create_virtualenv 05:28:56 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:28:56 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 05:28:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:28:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:57 INFO - Using real prefix '/usr' 05:28:57 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:28:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:59 INFO - Installing pip.................done. 05:28:59 INFO - Return code: 0 05:28:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:28:59 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 05:28:59 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 05:28:59 INFO - Using env: {'DISPLAY': ':0', 05:28:59 INFO - 'HOME': '/home/cltbld', 05:28:59 INFO - 'LANG': 'en_US.UTF-8', 05:28:59 INFO - 'LANGUAGE': 'en_US:en', 05:28:59 INFO - 'LOGNAME': 'cltbld', 05:28:59 INFO - 'MAIL': '/var/mail/cltbld', 05:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:59 INFO - 'MOZ_NO_REMOTE': '1', 05:28:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:59 INFO - 'NO_EM_RESTART': '1', 05:28:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:59 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:59 INFO - 'SHELL': '/bin/bash', 05:28:59 INFO - 'SHLVL': '1', 05:28:59 INFO - 'TERM': 'linux', 05:28:59 INFO - 'TMOUT': '86400', 05:28:59 INFO - 'USER': 'cltbld', 05:28:59 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:59 INFO - '_': '/tools/buildbot/bin/python'} 05:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:59 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 05:28:59 INFO - Cleaning up... 05:28:59 INFO - Return code: 0 05:28:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:28:59 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 05:28:59 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 05:28:59 INFO - Using env: {'DISPLAY': ':0', 05:28:59 INFO - 'HOME': '/home/cltbld', 05:28:59 INFO - 'LANG': 'en_US.UTF-8', 05:28:59 INFO - 'LANGUAGE': 'en_US:en', 05:28:59 INFO - 'LOGNAME': 'cltbld', 05:28:59 INFO - 'MAIL': '/var/mail/cltbld', 05:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:59 INFO - 'MOZ_NO_REMOTE': '1', 05:28:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:59 INFO - 'NO_EM_RESTART': '1', 05:28:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:59 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:59 INFO - 'SHELL': '/bin/bash', 05:28:59 INFO - 'SHLVL': '1', 05:28:59 INFO - 'TERM': 'linux', 05:28:59 INFO - 'TMOUT': '86400', 05:28:59 INFO - 'USER': 'cltbld', 05:28:59 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:59 INFO - '_': '/tools/buildbot/bin/python'} 05:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:00 INFO - Downloading/unpacking psutil>=0.7.1 05:29:00 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 05:29:00 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 05:29:00 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 05:29:00 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 05:29:00 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 05:29:00 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 05:29:01 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:29:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:29:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:01 INFO - Installing collected packages: psutil 05:29:01 INFO - Running setup.py install for psutil 05:29:01 INFO - building 'psutil._psutil_linux' extension 05:29:01 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 05:29:02 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 05:29:02 INFO - building 'psutil._psutil_posix' extension 05:29:02 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 05:29:02 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 05:29:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:02 INFO - Successfully installed psutil 05:29:02 INFO - Cleaning up... 05:29:02 INFO - Return code: 0 05:29:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:02 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 05:29:02 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 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 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 05:29:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:29:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:29:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:29:04 INFO - Installing collected packages: mozsystemmonitor 05:29:04 INFO - Running setup.py install for mozsystemmonitor 05:29:04 INFO - Successfully installed mozsystemmonitor 05:29:04 INFO - Cleaning up... 05:29:04 INFO - Return code: 0 05:29:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:29:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:29:04 INFO - Using env: {'DISPLAY': ':0', 05:29:04 INFO - 'HOME': '/home/cltbld', 05:29:04 INFO - 'LANG': 'en_US.UTF-8', 05:29:04 INFO - 'LANGUAGE': 'en_US:en', 05:29:04 INFO - 'LOGNAME': 'cltbld', 05:29:04 INFO - 'MAIL': '/var/mail/cltbld', 05:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:04 INFO - 'MOZ_NO_REMOTE': '1', 05:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:04 INFO - 'NO_EM_RESTART': '1', 05:29:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:04 INFO - 'SHELL': '/bin/bash', 05:29:04 INFO - 'SHLVL': '1', 05:29:04 INFO - 'TERM': 'linux', 05:29:04 INFO - 'TMOUT': '86400', 05:29:04 INFO - 'USER': 'cltbld', 05:29:04 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:04 INFO - '_': '/tools/buildbot/bin/python'} 05:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:29:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:29:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:29:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 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 05:29:06 INFO - Downloading docopt-0.6.1.tar.gz 05:29:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:29:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:07 INFO - Running setup.py install for blobuploader 05:29:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:07 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:29:07 INFO - Running setup.py install for requests 05:29:07 INFO - Running setup.py install for docopt 05:29:07 INFO - Successfully installed blobuploader requests docopt 05:29:07 INFO - Cleaning up... 05:29:07 INFO - Return code: 0 05:29:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:07 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 05:29:07 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 05:29:07 INFO - Using env: {'DISPLAY': ':0', 05:29:07 INFO - 'HOME': '/home/cltbld', 05:29:07 INFO - 'LANG': 'en_US.UTF-8', 05:29:07 INFO - 'LANGUAGE': 'en_US:en', 05:29:07 INFO - 'LOGNAME': 'cltbld', 05:29:07 INFO - 'MAIL': '/var/mail/cltbld', 05:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:07 INFO - 'MOZ_NO_REMOTE': '1', 05:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:07 INFO - 'NO_EM_RESTART': '1', 05:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:07 INFO - 'SHELL': '/bin/bash', 05:29:07 INFO - 'SHLVL': '1', 05:29:07 INFO - 'TERM': 'linux', 05:29:07 INFO - 'TMOUT': '86400', 05:29:07 INFO - 'USER': 'cltbld', 05:29:07 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:07 INFO - '_': '/tools/buildbot/bin/python'} 05:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:07 INFO - Running setup.py (path:/tmp/pip-jnL8CT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:07 INFO - Running setup.py (path:/tmp/pip-KU10U7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:08 INFO - Running setup.py (path:/tmp/pip-KHv1BH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:08 INFO - Running setup.py (path:/tmp/pip-W6mvq9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:08 INFO - Running setup.py (path:/tmp/pip-rnUOt_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:08 INFO - Running setup.py (path:/tmp/pip-PpMIK7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:08 INFO - Running setup.py (path:/tmp/pip-HrRLoC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:08 INFO - Running setup.py (path:/tmp/pip-2LT7ww-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:08 INFO - Running setup.py (path:/tmp/pip-RiomdW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:08 INFO - Running setup.py (path:/tmp/pip-BJ8Ob8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:08 INFO - Running setup.py (path:/tmp/pip-HrYGhQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:08 INFO - Running setup.py (path:/tmp/pip-i0o0i0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:08 INFO - Running setup.py (path:/tmp/pip-cE0lZ0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:08 INFO - Running setup.py (path:/tmp/pip-M5rA64-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:08 INFO - Running setup.py (path:/tmp/pip-KIkTKH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:09 INFO - Running setup.py (path:/tmp/pip-941qHd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:09 INFO - Running setup.py (path:/tmp/pip-OmHIMn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:29:09 INFO - Running setup.py install for manifestparser 05:29:09 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Running setup.py install for mozcrash 05:29:09 INFO - Running setup.py install for mozdebug 05:29:09 INFO - Running setup.py install for mozdevice 05:29:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Running setup.py install for mozfile 05:29:09 INFO - Running setup.py install for mozhttpd 05:29:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Running setup.py install for mozinfo 05:29:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Running setup.py install for mozInstall 05:29:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:29:09 INFO - Running setup.py install for mozleak 05:29:10 INFO - Running setup.py install for mozlog 05:29:10 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Running setup.py install for moznetwork 05:29:10 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Running setup.py install for mozprocess 05:29:10 INFO - Running setup.py install for mozprofile 05:29:10 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Running setup.py install for mozrunner 05:29:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Running setup.py install for mozscreenshot 05:29:10 INFO - Running setup.py install for moztest 05:29:10 INFO - Running setup.py install for mozversion 05:29:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:29:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:29:10 INFO - Cleaning up... 05:29:10 INFO - Return code: 0 05:29:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:10 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 05:29:10 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 05:29:10 INFO - Using env: {'DISPLAY': ':0', 05:29:10 INFO - 'HOME': '/home/cltbld', 05:29:10 INFO - 'LANG': 'en_US.UTF-8', 05:29:10 INFO - 'LANGUAGE': 'en_US:en', 05:29:10 INFO - 'LOGNAME': 'cltbld', 05:29:10 INFO - 'MAIL': '/var/mail/cltbld', 05:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:10 INFO - 'MOZ_NO_REMOTE': '1', 05:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:10 INFO - 'NO_EM_RESTART': '1', 05:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:10 INFO - 'SHELL': '/bin/bash', 05:29:10 INFO - 'SHLVL': '1', 05:29:10 INFO - 'TERM': 'linux', 05:29:10 INFO - 'TMOUT': '86400', 05:29:10 INFO - 'USER': 'cltbld', 05:29:10 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:10 INFO - '_': '/tools/buildbot/bin/python'} 05:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:11 INFO - Running setup.py (path:/tmp/pip-5Dj3xF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:11 INFO - Running setup.py (path:/tmp/pip-BRUBci-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:11 INFO - Running setup.py (path:/tmp/pip-vOeWCS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:11 INFO - Running setup.py (path:/tmp/pip-yo8Nay-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:11 INFO - Running setup.py (path:/tmp/pip-cMbpCz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:11 INFO - Running setup.py (path:/tmp/pip-u3BVMr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:11 INFO - Running setup.py (path:/tmp/pip-lPTO83-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:11 INFO - Running setup.py (path:/tmp/pip-buMCRB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:11 INFO - Running setup.py (path:/tmp/pip-4IV2Ca-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:11 INFO - Running setup.py (path:/tmp/pip-8eEMMN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:11 INFO - Running setup.py (path:/tmp/pip-4WBPUx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:11 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)) 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:11 INFO - Running setup.py (path:/tmp/pip-fmpwk8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:12 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)) 05:29:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:12 INFO - Running setup.py (path:/tmp/pip-aGhh6K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:12 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)) 05:29:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:12 INFO - Running setup.py (path:/tmp/pip-7Jd6C5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:12 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)) 05:29:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:12 INFO - Running setup.py (path:/tmp/pip-ZAAb8t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:12 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)) 05:29:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:12 INFO - Running setup.py (path:/tmp/pip-fFrmks-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:12 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)) 05:29:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:12 INFO - Running setup.py (path:/tmp/pip-pF5yjN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:12 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)) 05:29:12 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)) 05:29:12 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)) 05:29:12 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)) 05:29:12 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)) 05:29:12 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)) 05:29:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:14 INFO - Downloading blessings-1.5.1.tar.gz 05:29:14 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 05:29:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:14 INFO - Installing collected packages: blessings 05:29:14 INFO - Running setup.py install for blessings 05:29:14 INFO - Successfully installed blessings 05:29:14 INFO - Cleaning up... 05:29:14 INFO - Return code: 0 05:29:14 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:29:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:14 INFO - Reading from file tmpfile_stdout 05:29:14 INFO - Current package versions: 05:29:14 INFO - argparse == 1.2.1 05:29:14 INFO - blessings == 1.5.1 05:29:14 INFO - blobuploader == 1.2.4 05:29:14 INFO - docopt == 0.6.1 05:29:14 INFO - manifestparser == 1.1 05:29:14 INFO - mozInstall == 1.12 05:29:14 INFO - mozcrash == 0.16 05:29:14 INFO - mozdebug == 0.1 05:29:14 INFO - mozdevice == 0.47 05:29:14 INFO - mozfile == 1.2 05:29:14 INFO - mozhttpd == 0.7 05:29:14 INFO - mozinfo == 0.9 05:29:14 INFO - mozleak == 0.1 05:29:14 INFO - mozlog == 3.1 05:29:14 INFO - moznetwork == 0.27 05:29:14 INFO - mozprocess == 0.22 05:29:14 INFO - mozprofile == 0.27 05:29:14 INFO - mozrunner == 6.11 05:29:14 INFO - mozscreenshot == 0.1 05:29:14 INFO - mozsystemmonitor == 0.0 05:29:14 INFO - moztest == 0.7 05:29:14 INFO - mozversion == 1.4 05:29:14 INFO - psutil == 3.1.1 05:29:14 INFO - requests == 1.2.3 05:29:14 INFO - wsgiref == 0.1.2 05:29:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:14 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')]} 05:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:14 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')]} 05:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:14 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 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:14 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 05:29:14 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 05:29:14 INFO - Using env: {'DISPLAY': ':0', 05:29:14 INFO - 'HOME': '/home/cltbld', 05:29:14 INFO - 'LANG': 'en_US.UTF-8', 05:29:14 INFO - 'LANGUAGE': 'en_US:en', 05:29:14 INFO - 'LOGNAME': 'cltbld', 05:29:14 INFO - 'MAIL': '/var/mail/cltbld', 05:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:14 INFO - 'MOZ_NO_REMOTE': '1', 05:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:14 INFO - 'NO_EM_RESTART': '1', 05:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:14 INFO - 'SHELL': '/bin/bash', 05:29:14 INFO - 'SHLVL': '1', 05:29:14 INFO - 'TERM': 'linux', 05:29:14 INFO - 'TMOUT': '86400', 05:29:14 INFO - 'USER': 'cltbld', 05:29:14 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:14 INFO - '_': '/tools/buildbot/bin/python'} 05:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 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)) 05:29:14 INFO - Cleaning up... 05:29:14 INFO - Return code: 0 05:29:14 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:29:14 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')]} 05:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:14 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')]} 05:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:14 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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:14 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', 'jsonschema'] in /builds/slave/test-pgo/build 05:29:14 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 jsonschema 05:29:14 INFO - Using env: {'DISPLAY': ':0', 05:29:14 INFO - 'HOME': '/home/cltbld', 05:29:14 INFO - 'LANG': 'en_US.UTF-8', 05:29:14 INFO - 'LANGUAGE': 'en_US:en', 05:29:14 INFO - 'LOGNAME': 'cltbld', 05:29:14 INFO - 'MAIL': '/var/mail/cltbld', 05:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:14 INFO - 'MOZ_NO_REMOTE': '1', 05:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:14 INFO - 'NO_EM_RESTART': '1', 05:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:14 INFO - 'SHELL': '/bin/bash', 05:29:14 INFO - 'SHLVL': '1', 05:29:14 INFO - 'TERM': 'linux', 05:29:14 INFO - 'TMOUT': '86400', 05:29:14 INFO - 'USER': 'cltbld', 05:29:14 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:14 INFO - '_': '/tools/buildbot/bin/python'} 05:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:14 INFO - Downloading/unpacking jsonschema 05:29:14 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 05:29:14 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 05:29:14 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 05:29:14 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 05:29:14 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 05:29:14 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 05:29:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:29:16 INFO - Downloading/unpacking functools32 (from jsonschema) 05:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:29:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:16 INFO - warning: no files found matching '*.txt' 05:29:16 INFO - no previously-included directories found matching 'build' 05:29:16 INFO - no previously-included directories found matching 'dist' 05:29:16 INFO - no previously-included directories found matching '.git*' 05:29:16 INFO - Installing collected packages: jsonschema, functools32 05:29:16 INFO - Running setup.py install for functools32 05:29:17 INFO - warning: no files found matching '*.txt' 05:29:17 INFO - no previously-included directories found matching 'build' 05:29:17 INFO - no previously-included directories found matching 'dist' 05:29:17 INFO - no previously-included directories found matching '.git*' 05:29:17 INFO - Successfully installed jsonschema functools32 05:29:17 INFO - Cleaning up... 05:29:17 INFO - Return code: 0 05:29:17 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:29:17 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')]} 05:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 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')]} 05:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f530a0c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', '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 05:29:17 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', 'flake8'] in /builds/slave/test-pgo/build 05:29:17 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 flake8 05:29:17 INFO - Using env: {'DISPLAY': ':0', 05:29:17 INFO - 'HOME': '/home/cltbld', 05:29:17 INFO - 'LANG': 'en_US.UTF-8', 05:29:17 INFO - 'LANGUAGE': 'en_US:en', 05:29:17 INFO - 'LOGNAME': 'cltbld', 05:29:17 INFO - 'MAIL': '/var/mail/cltbld', 05:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:17 INFO - 'MOZ_NO_REMOTE': '1', 05:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:17 INFO - 'NO_EM_RESTART': '1', 05:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:17 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:17 INFO - 'SHELL': '/bin/bash', 05:29:17 INFO - 'SHLVL': '1', 05:29:17 INFO - 'TERM': 'linux', 05:29:17 INFO - 'TMOUT': '86400', 05:29:17 INFO - 'USER': 'cltbld', 05:29:17 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:17 INFO - '_': '/tools/buildbot/bin/python'} 05:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:17 INFO - Downloading/unpacking flake8 05:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:29:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:29:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:29:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:29:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:29:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:29:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:29:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:19 INFO - no previously-included directories found matching 'docs/_build' 05:29:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:29:19 INFO - Running setup.py install for pep8 05:29:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:19 INFO - no previously-included directories found matching 'docs/_build' 05:29:19 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:29:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:29:19 INFO - Cleaning up... 05:29:19 INFO - Return code: 0 05:29:19 INFO - Running post-action listener: _resource_record_post_action 05:29:19 INFO - Running post-action listener: _start_resource_monitoring 05:29:19 INFO - Starting resource monitoring. 05:29:19 INFO - ##### 05:29:19 INFO - ##### Running install step. 05:29:19 INFO - ##### 05:29:19 INFO - Running pre-action listener: _resource_record_pre_action 05:29:19 INFO - Running main action method: install 05:29:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:20 INFO - Reading from file tmpfile_stdout 05:29:20 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:29:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:29:20 INFO - Reading from file tmpfile_stdout 05:29:20 INFO - Output received: 05:29:20 INFO - Usage: mozinstall [options] installer 05:29:20 INFO - Options: 05:29:20 INFO - -h, --help show this help message and exit 05:29:20 INFO - -d DEST, --destination=DEST 05:29:20 INFO - Directory to install application into. [default: 05:29:20 INFO - "/builds/slave/test-pgo"] 05:29:20 INFO - --app=APP Application being installed. [default: firefox] 05:29:20 INFO - mkdir: /builds/slave/test-pgo/build/application 05:29:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:29:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:29:35 INFO - Reading from file tmpfile_stdout 05:29:35 INFO - Output received: 05:29:35 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:29:35 INFO - Running post-action listener: _resource_record_post_action 05:29:35 INFO - ##### 05:29:35 INFO - ##### Running run-tests step. 05:29:35 INFO - ##### 05:29:35 INFO - Running pre-action listener: _resource_record_pre_action 05:29:35 INFO - Running main action method: run_tests 05:29:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:29:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:29:35 INFO - Python 2.7.3 05:29:35 INFO - Return code: 0 05:29:35 INFO - grabbing minidump binary from tooltool 05:29:35 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')]} 05:29:35 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 0x25d0350>, '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 0x25d1250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d16e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:29:35 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 05:29:35 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 05:29:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:36 INFO - Return code: 0 05:29:36 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:29:36 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:29:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:29:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:29:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:29:36 INFO - Return code: 0 05:29:36 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/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:29:36 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/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --webServer localhost 05:29:36 INFO - Using env: {'DISPLAY': ':0', 05:29:36 INFO - 'HOME': '/home/cltbld', 05:29:36 INFO - 'LANG': 'en_US.UTF-8', 05:29:36 INFO - 'LANGUAGE': 'en_US:en', 05:29:36 INFO - 'LOGNAME': 'cltbld', 05:29:36 INFO - 'MAIL': '/var/mail/cltbld', 05:29:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:36 INFO - 'MOZ_NO_REMOTE': '1', 05:29:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:36 INFO - 'NO_EM_RESTART': '1', 05:29:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:36 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:29:36 INFO - 'SHELL': '/bin/bash', 05:29:36 INFO - 'SHLVL': '1', 05:29:36 INFO - 'TERM': 'linux', 05:29:36 INFO - 'TMOUT': '86400', 05:29:36 INFO - 'USER': 'cltbld', 05:29:36 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548', 05:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:36 INFO - '_': '/tools/buildbot/bin/python'} 05:29:36 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/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] with output_timeout 3600 05:29:37 INFO - mozversion INFO | application_buildid: 20151218030232 05:29:37 INFO - mozversion INFO | application_changeset: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 05:29:37 INFO - mozversion INFO | application_display_name: Nightly 05:29:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:37 INFO - mozversion INFO | application_name: Firefox 05:29:37 INFO - mozversion INFO | application_remotingname: firefox 05:29:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:29:37 INFO - mozversion INFO | application_vendor: Mozilla 05:29:37 INFO - mozversion INFO | application_version: 46.0a1 05:29:37 INFO - mozversion INFO | platform_buildid: 20151218030232 05:29:37 INFO - mozversion INFO | platform_changeset: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 05:29:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:29:37 INFO - mozversion INFO | platform_version: 46.0a1 05:29:37 INFO - 2015-12-18 05:29:37,155 DEBUG : using testdate: 1450445377 05:29:37 INFO - 2015-12-18 05:29:37,156 DEBUG : actual date: 1450445377 05:29:37 INFO - 2015-12-18 05:29:37,165 INFO : starting webserver on 'localhost:49446' 05:29:37 INFO - 2015-12-18 05:29:37,166 INFO : Starting test suite talos-linux64-ix-068.e 05:29:37 INFO - 2015-12-18 05:29:37,166 INFO : Starting test dromaeo_css 05:29:37 INFO - 2015-12-18 05:29:37,166 DEBUG : operating with platform_type : linux_ 05:29:37 INFO - 2015-12-18 05:29:37,167 INFO : Initialising browser for dromaeo_css test... 05:29:37 INFO - 2015-12-18 05:29:37,184 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOUwb9g/profile http://localhost:49446/getInfo.html 05:29:41 INFO - 2015-12-18 05:29:41,608 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:29:41 INFO - 2015-12-18 05:29:41,608 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:29:41 INFO - 2015-12-18 05:29:41,608 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:29:41 INFO - 2015-12-18 05:29:41,608 DEBUG : BROWSER_OUTPUT: __metrics 05:29:41 INFO - 2015-12-18 05:29:41,709 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:29:42 INFO - 2015-12-18 05:29:42,604 INFO : Browser initialized. 05:29:42 INFO - 2015-12-18 05:29:42,605 INFO : Running cycle 1/1 for dromaeo_css test... 05:29:42 INFO - 2015-12-18 05:29:42,605 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOUwb9g/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:29:54 INFO - 2015-12-18 05:29:54,436 DEBUG : BROWSER_OUTPUT: RSS: Main: 150663168 05:29:54 INFO - 2015-12-18 05:29:54,436 DEBUG : BROWSER_OUTPUT: 05:30:44 INFO - 2015-12-18 05:30:44,049 DEBUG : BROWSER_OUTPUT: 1450445444046 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] 05:32:53 INFO - 2015-12-18 05:32:53,158 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49446/tests/dromaeo/cssquery-ext.html) 05:32:54 INFO - 2015-12-18 05:32:54,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 156733440 05:32:54 INFO - 2015-12-18 05:32:54,364 DEBUG : BROWSER_OUTPUT: 05:35:56 INFO - 2015-12-18 05:35:56,256 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-ext.html (next: http://localhost:49446/tests/dromaeo/cssquery-jquery.html) 05:35:56 INFO - 2015-12-18 05:35:56,837 DEBUG : BROWSER_OUTPUT: RSS: Main: 154050560 05:35:56 INFO - 2015-12-18 05:35:56,838 DEBUG : BROWSER_OUTPUT: 05:38:58 INFO - 2015-12-18 05:38:58,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49446/tests/dromaeo/cssquery-mootools.html) 05:38:59 INFO - 2015-12-18 05:38:59,109 DEBUG : BROWSER_OUTPUT: RSS: Main: 153354240 05:38:59 INFO - 2015-12-18 05:38:59,109 DEBUG : BROWSER_OUTPUT: 05:42:00 INFO - 2015-12-18 05:42:00,750 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49446/tests/dromaeo/cssquery-prototype.html) 05:42:01 INFO - 2015-12-18 05:42:01,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 152104960 05:42:01 INFO - 2015-12-18 05:42:01,364 DEBUG : BROWSER_OUTPUT: 05:45:03 INFO - 2015-12-18 05:45:03,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49446/tests/dromaeo/cssquery-yui.html) 05:45:04 INFO - 2015-12-18 05:45:04,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 152342528 05:45:04 INFO - 2015-12-18 05:45:04,209 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-yui.html (next: http://localhost:49446/tests/dromaeo/cssquery-dojo.html) 05:48:06 INFO - 2015-12-18 05:48:06,674 DEBUG : BROWSER_OUTPUT: RSS: Main: 155848704 05:48:06 INFO - 2015-12-18 05:48:06,674 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,674 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:48:06 INFO - 2015-12-18 05:48:06,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:48:06 INFO - 2015-12-18 05:48:06,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:48:06 INFO - 2015-12-18 05:48:06,676 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:48:06 INFO - 2015-12-18 05:48:06,676 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3223.2586505103827;3239.2873567815086;3243.4486137356653;3265.085775982726;3304.2895777407884;5379.273798037264;5519.668819870811;5534.944650553493;5543.168524721289;5596.608237423382;5394.379646340672;5443.8094666686675;5460.290162278908;5545.029619816536;5549.5837812164045;4889.633277504184;5020;5052.6210534209895;5076.060928728197;5093.363329583802;5047.924281135786;5092.618053645972;5159.58723302135;5207.3490813648295;5210.687358758468;4869.172240719086;4978.377702787152;5088;5099.694018358873;5188.221766734982;4959.504049595048;5039.269305950654;5042.067217564762;5053.343065401475;5054.519820617035;4960.727160006199;4974.925376119361;4975.975120124413;4978.925316120261;4984.003199360142;5084.313617661643;5197.350331208599;5244.973775131138;5290.709011004393;5328.413874473805;5872.354041055481;5888.381719919433;5915.792947246869;5931.436513531207;5962.314333851619;5897.14491398749;5935.584509084366;5958.821235362946;5965.4929331006915;5965.731542080617;5530.087535556629;5537.667739935577;5541.9168712469345;5542.778288868481;5556.333240011184;5822.650640961556;5959.255093113361;6023.668698221552;6115.9401541424295;6128.686460748315;5758.3090029196765;5867.325257595346;5927.9407205928255;5928.851778705481;5955.583109182316;5838.8540286492325;5920.79277225295;6003.279606447254;6013.51891848651;6019.969900150471;5786.392428794916;5873.618214816023;5875.265591801025;5914.822555323347;5924.911126333109;5865.20819689345;6003.649178934705;6043.942310095804;6055.273367195965;6059.060845568855;4496.955030449719;4575.130725162208;4624.884377890579;4648.604868586141;4670.883227919329;3983.1038016446646;4045;4045.8786236412957;4073.5315438724333;4104.712670113064;5646.689432081275;5655;5680.6591604503865;5695.088785794336;5696.287964004499;5924.822255332347;6011.969940150271;6044;6049.122877182784;6073.726682299307;5703.885922281531;5710.086386178275;5721.485066344049;5747;5751.741171647286;5230.895382092337;5235.764390602432;5239.030779305841;5242.16125419931;5291.920621190685;5935.851603709942;6013.849653758603;6058.575899687068;6148.44663980244;6167.167432396659;6117.816465506042;6125.754969801158;6136.447719705248;6137.877242455036;6144.846378840565;6116.480099191618;6180.3798098957595;6222.719977601109;6243.313235544178;6247.344028877084;5751.769929202785;5850.619709718854;5890.764369425175;5973.163757073926;5992.340842507234;5994.910076348771;6027.909581356195;6037.879242415215;6040.879182416415;6044.304904935989;5863.882722345615;5867.501262392744;5869.325027621708;5885.823425297248;5889.322727886177;5621.325440947113;5693.3168019837885;5788.797392091256;5792.391798861059;5860.267466566679;5652.604317697722;5685.431456854281;5697.7720891165545;5710.114932185518;5799.4780469757925;5753.165790960371;5763.423657634203;5826.155207494974;5836.212111364912;5842.269716285465;5579.079451890392;5664.178694089416;5692.373838877803;5738.053221218453;5781.075027995669 05:48:06 INFO - 2015-12-18 05:48:06,677 DEBUG : BROWSER_OUTPUT: |1;ext.html;564.4834975996963;616.6454288783949;621.1490258346066;621.3320680268711;624.0763669768745;35488.822555887215;35829.2834143317;36040;36456;36491.81754091229;38710.8064459678;38777;39044.804775976154;39125.80437097818;39255;8494.108118647546;8661.913380866205;8674.04585495595;8683.30533557317;8689.435186712843;8449.408541402105;8493.36299777516;8561.315094792433;8718.258947989396;8723.651053957834;8685.913140868573;8697.652093916267;8743.213110820025;8749.562521873913;8766.167214114648;8559.186877246651;8647.870281945776;8656.307495400415;8678.175573320525;8683.218510334069;8599.226069653698;8678.913210867873;8703.52130632815;8706.782330441727;8731.126887311282;39530.60469395298;39567.40648890269;39622.405663915066;39622.405663915066;39788.80105599453;2991.222282206642;3000.564918086887;3039.7720170987045;3082.22944263934;3091.655130018449;29110.854445727848;29124.854375727984;29151.854240728873;29318.853405733047;29513.40973180546;3711.31340701971;3714.99695082329;3852.768833869977;3858.884233473;3918.8236529356245;261273;262473;262647;263217;263311;55027.724861375435;56017.43982560122;56938.430615693316;57014.42985570174;57219;62073;62629;62943.6852815733;63105.684471578264;63194;12816.871831281567;12911.5480958166;12936.094473386773;12969.740605187844;13043.086983911226;9438.716838494718;9445;9475.810483790285;9479.383840050376;9494.810103798023;19185.32851350196;19405.611887762167;19420.708689369672;19464.70802937957;19514.70727939082;7240.031587049284;7348.853022939511;7376.557406555624;7428.62856857155;7470.887936680954;965.7778710896392;973.1873885305849;1064.9787004259872;1076.5263284155103;1100.1583788401879;27869.72130278671;29237.561436578468;29415;30042;30076.69923300783;1017.7506510904876;1037.6160820496314;1049.4018409506507;1090.4166271044953;1095.6548687163518;14014.439422422989;14050.508232211823;14648.926755366369;14660.633484162981;15333.923330383499;302397;303296;303859;304223;306256;52179.47820521746;52334;52432.47567524352;52516.73741631267;52603;1361.1220762608261;1363.5432130236481;1375.326090215807;1376.6351916742071;1379.5516457151466;27791.30521736973;28028.85985570059;28512.85743571269;28969.420611588073;28994.42011159723;45726.77136614296;45869.77065114653;45960.54039459563;46107.53892461167;46270;287321;288097;288681;289170;289510;30049.596032316924;30255;30385.544216836322;30649.080527584207;30650.693493064784;41807.790961045;42106.789466052476;42274.15451690887;42330.15339693127;42877.78561107175;35004.89383377014;35403.64596354003;35464.46803298004;36205.28460365536;36843.447348290334;35080.82459587686;35316;35567.46648800322;35628.64371356253;35790.28419431545;34294.828525857214;35514.28971420609;35654.821725891205;35663.28673426465;35712.821435892656;29917.850410747807;32656.673433265365;32839.17902052468;33146;33221.16947076342;24292.392690182885;24660.383490412165;25108.874455627603;25455.61816572716;25466.61800072963 05:48:06 INFO - 2015-12-18 05:48:06,680 DEBUG : BROWSER_OUTPUT: |2;jquery.html;361.56431500042453;363.30971154805866;363.4638907611273;364.19876272201157;368.5301240917828;16300.103494307808;17240.82759172408;17415.73876391855;17856.196471158797;17971.460856174337;13953.232572208524;13993.0904491208;14045.157290562573;14084.57746267611;14209.289535523234;4442.222611043071;4762.832716243703;4918.262260660894;4955.331030310898;4976.328195693572;4896.804127834882;4923.064617722621;4948.752562371885;4957.182064959277;4967.155583550787;4820.084184005028;4935.333729946447;4978.751062446863;5089.389273287211;5109.4379618241965;5055.519725626078;5064.696118232918;5066.847994560169;5079.212722028092;5125.513076257749;5110.8466745997675;5132.820351287687;5133.8203162889495;5135.4094279158;5147.382314122329;12375.814362784566;12549.1215614907;17213.48359549203;20049.298274560322;20229.696554551694;2143.474848662088;2148.86032407893;2157.967630485544;2184.224600266898;2246.4608493961496;9431.245500359955;9554.856677149848;9558.808823823554;9676.080772326688;10173.694789156338;1827.7715285589302;1840.1903162608544;1864.6270745850884;1873.297513432463;1886.1606585069649;59760.7384517552;62790;63279.367206327806;63657.363426365606;63854;9949.80100397988;10170.542325595366;10282.640107596199;10370.637027703995;10381.169506439466;13417.865821341857;13503.797443038362;13616.387262573207;13638.727225455634;13672.248026358448;7037.22590515043;7111.217766045731;7117.4306055515435;7194.46041546886;7195.2444993276;6724.798256052326;6725.831854203684;6741.393274605309;6745.494087943423;6777;4318.222719910384;4368.803403846835;4381.16757816014;4389.868303950887;4400.493943196573;1764.4089230107802;1769.4514700442903;1838.5587459009741;1875.36237679187;1897.8671492995634;423.3945457995097;433.6119173339843;438.27465544523864;452.74872445792244;472.9361536192639;9998.500074996222;11106.278091924;11298.209125361145;11309.377984210783;11390.373529455794;455.70606958511576;460.619988509481;476.86409373328434;482.9034193161382;489.99755001224764;5964.522838172936;6040.939590604038;6053.848653783691;6081.300650425258;6144.109104179869;527297;542425;551774;554345;571571;83044.58477707572;84187;84588;84590.15409845822;85027.57486212654;478.2634742496586;478.67210960492173;480.0518975024299;480.8821838649553;482.54881685623354;10441.738956526147;10603.204759642904;14656.926715366355;14688.192149431883;15046.849531504546;34269.31461370709;35392.11519712028;35561.82219088888;36372;36439.817800911886;42375.36436953386;45720.54279457163;52022;52077.479225207266;53179.468205319;9407.576659050495;9679.564419600994;9737.707868763948;9741.025897410202;9750.561224744763;11032.613858514913;11112.166587506132;17610;18150.818491814913;18281.908590456962;14663.70672586521;14801.037932534351;15020.94853360298;15312.081275123528;15354.769678455055;14807.555773326818;14812.703745924806;15029.398824046915;15045;15552.222388880284;14723.33744981457;14867.107973521192;15006.774898376314;15442.613934651723;15458.922705386402;9446.811063778548;9597.376170548892;9704.660336888175;9748.610055597981;9758.707238782565;13102.410391532594;14315.28423578796;14529.273536323353;14677.853221467649;14881.47914822976 05:48:06 INFO - 2015-12-18 05:48:06,682 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1482.9258537073144;1742.7995780485246;1802.6755184066872;1804.9639007219855;1813.8548916086706;6438.420542151205;6610.834729131774;7343.559386436811;7855.685772569091;7862.606869656515;7359.484836061478;7517.887231691515;7528.096628404599;7530.322270995609;7536.62316884155;2230.966535501969;2266.8073213776847;2268.4328917770476;2272.704548408713;2276.635738281875;2224.688543603897;2271.693321401606;2338.485533182697;2344.0623750499767;2344.402177444749;2267.0705010945517;2268.262814585258;2293.266154830455;2304.7810458006547;2305.7002589663402;2150.3333966470286;2180.2260197629853;2186.5189658275153;2215.7481023221826;2226.6214743493647;2275.6472746724285;2323.047550504302;2327.44141406063;2328.2549584133085;2341.4029422497324;7419.812829947183;7463.14173870001;7493.437992150556;7498.47510674253;7510.3616192623695;4567.429071366079;4625.537446255381;4636.466806317249;4649.372334734801;4679.251319788817;2988.372441787227;2998.4902566563737;3053.4503789317914;3075.5386691996155;3102.301982054042;963.8361478548663;965.2567523007245;993.6671215142936;1015.5125539740966;1017.9898201018043;10178.134858537032;10315.071643552117;10333.741656458573;10333.793324133476;10351.430671313075;4205.222033923673;4228.365745138254;4253.106847561985;4256.084941737541;4265.957340426618;8990.595423205972;9061.184493395625;9062.320325975446;9063.13900179482;9074.727758167266;4640.048789998043;4645.070985802853;4676.906461870743;4679.906401871944;4695.835645752451;4077.082656402286;4203.201391735561;4222.493300803923;4228.873133805991;4259.190753756776;3516.7362447816517;3517.577890653138;3572.946405803863;3588.4258518636893;3592.5868525119945;1077.450500244865;1080.1089101491302;1087.7770057138268;1090.1061129873585;1092.448313601626;1075.9731006724737;1079.082779637302;1087.8259478483405;1249.506444954238;1363.277462944641;7140.321669441395;7225.421966242688;7241.637918104074;7257.419406447471;7292.635368231673;492.24194740099847;497.5472320188611;498.9850304490871;505.36576596372055;505.53490788474693;5538.944610553843;5539.169124631257;5562.137868630369;5562.972185139049;5563.165525171175;8614.827703445895;8773.166549177819;8868;8886.511241881759;8930;8697.652093916298;8717.476951382938;8766.167214114648;8770.254528365032;8847.601857916408;7095.432365410755;7132.251113633098;7137.500374973702;7210.4231661468;7223.783286501413;6980.860382792417;7005.614691192033;7020.5787652741;7043.7182512700965;7047.048648432601;7769.611519423893;7830.530168189927;7862.253085956825;7869.606519673879;7997.800054998672;8072.192780721881;8083.514989100673;8136.593170341577;8148.103708592169;8203.917960820316;6438.3883531064475;6683.198016238082;6690.498212633975;6753.358430949052;6805.489588280998;7044.401225895856;7140.036095127091;7140.500164988609;7150.463715221484;7174.426045916419;6770.492213084137;6854.897176542456;6857.55425897315;6903.1716194055025;6909.93090069113;6711.127553418024;6714.52998290115;6768.593884366954;6871.553349032297;6875.7937261882225;6644.435223006099;6677;6708.765193218214;6728.562643428161;6729.327067293231;6198.938010619836;6245.250569931637;6251.156093927261;6254.155688981929;6256.843578910381;6086.878262434815;6099.2985806632805;6116.204893363834;6128.050152226412;6129.264488261437 05:48:06 INFO - 2015-12-18 05:48:06,685 DEBUG : BROWSER_OUTPUT: |4;prototype.html;335.74147906112296;335.8505465068044;341.5798567761653;342.81316682408084;346.5581383735737;6281.214848143982;6294.811155665326;6304.968475157618;6330.208723909512;6469.450096741771;6269.749210031593;6275.309715931244;6280.685965701719;6287.465565426933;6309.716062777175;4707.599854012413;4713.10451014307;4730;4736.478987311393;4739.668223224376;4731.266653668669;4740.288956656512;4747.8338258160975;4750.406199225097;4754.072955773626;4583.335416364625;4646.605038571709;4662.137504561667;4664.696794708333;4675.438947326308;4651;4651.395318608593;4656.767161641938;4661.906761864744;4665.9066818663605;4014.9397759033636;4027.2549578328103;4029.5164580250266;4035.1929614077303;4043.65628921542;5992.8501787455225;6002.759889604411;6005.429484198994;6011.0382338825575;6024.367441418632;5327.733613319358;5415.458454154553;5456.290682211326;5484.451554844523;5487.314085739283;6642.070110184578;6676.899846502306;6679.432248258904;6682.665866706645;6683.198016238034;4469.7988590513505;4498.775061246924;4502.549745025504;4507.526709695468;4511.954880451186;6797.320267973163;6819.897701534481;6850.21222559407;6908;6968.0941477607585;4641.234196357631;4643.373144625432;4650.51169627191;4651.534846515321;4663.137319595886;4702.341672165831;4708.458527269339;4720.221163507983;4725.8582242532775;4729.456112547033;5481.068218402801;5481.671099734029;5481.9036192761605;5483.232347471358;5491.588130890199;6575.967120164369;6711.429528490035;6719.899201511981;6742.831429214309;6770.52606317553;1624.8212696603364;1632.959176020609;1663.7421199713822;1664.2594045649569;1665.992074794756;1096.0792933935531;1105.745678493951;1111.74985628233;1113.9888601114046;1122.2144498850837;6298.0552917062805;6305.054241863757;6323;6372.681365931685;6391.265004524539;6808.2170550386345;6843.076184715;6844.691988860513;6864.828379290558;6873.793786186422;6293.779717709858;6353.96823015882;6361.554691171574;6362.809115726535;6374.330695277022;3770.019794853303;3803.4294855771304;3810.618938106167;3816.217675376541;3817.6373244541724;7148.428125749928;7151;7151.82120446993;7171.42628589722;7177.1746249182015;7061.046758687614;7226.891596626055;7235.819104522429;7238.457115716343;7256.7460138894885;7169.032180655649;7171.462140339496;7173.1392232932385;7179.784606461814;7181.06646135999;7019.824504387431;7028.051213086168;7028.121484814398;7031.191412987368;7034.824129396806;6777.152855893013;6938.167419909643;6947.7220911162985;6953.548019378724;6957.060796792571;7109.573425594481;7132.857342853217;7136.357727804522;7141.892871606826;7152;4559.361689363425;4592.334111553872;4596.724196548218;4603.033362993675;4604.240300350405;6864.107666003388;6929.930700692928;6980.162380514371;6983.825404364931;6994.160700715946;6790.694418751069;6826.283240259701;6828.419584335387;6829.146356705412;6850.8972365413565;6726.596404215763;6740.056392105207;6776.28848970851;6811.421029212374;6826.829329266808;6513.576617520036;6655.900161497485;6692.063111164538;6701.631410272482;6703.530752847449;6838.418734407432;6843.3840954314355;6845.589264643938;6846.691898864663;6858.759943401957;6640.369164929324;6650;6656.800295991128;6665.100211471583;6674.933250667431 05:48:06 INFO - 2015-12-18 05:48:06,685 DEBUG : BROWSER_OUTPUT: |5;yui.html;828.6892415344246;842.2209456252964;845.463130911871;847.8050048488843;858.1461445861369;13230.933845330772;13371.933140334284;13396.66508337292;13544.051916365846;13577.66055848603;12472.376381180928;12667.619971400873;12690.175138615983;12723.10938234324;13150.73698526029;824.3199360527559;833.7165363776314;833.8582440985047;836.5858899844585;842.5913431985513;831.5260301628074;840.537704262653;840.756180707594;842.9747107586783;844.6114787197896;870.268974061788;871.860502319629;875.7022612311813;877.3770622857746;879.2438502887512;871.1201686296854;873.9126087391273;876.3077169036517;877.1535468273147;877.3551439691821;639.3829954094322;642.383312020461;644.3041515163612;645.6319897658336;646.6766616691655;12192.939035304855;12235.93882030593;12297.3236471994;12390.81413778794;12430.440630171574;10578.418186999712;10640.467976601216;10811.567537298499;10888.945555272252;10937.12502999766;9375.812483750355;9451.29115316354;9583.856242156373;9624.855627165598;9785.217182625373;3528.311979164064;3583.3549961007006;3595.514605528246;3606.1705807664384;3609;42460.150796983886;43292.13415731699;44949.775251123865;45109.323360149625;50556.99999999963;9107.316951228684;9131.13254240846;9148.542572871329;9250.259979201648;9346.953265233631;17062.146892655317;17081.572960675832;17199.312027518758;17241.741373879402;17432.65134697299;7508.924910750822;7612.352949999312;7621.54270743746;7674.731384401519;7997.320227780588;4289.785510724514;4325.437693099877;4348.695591308578;4393.494748104009;4452.888677783017;3051.8016328938547;3079.414911166871;3080.630324361091;3081;3086.382723455318;562.4038519169693;568.2130249604245;572.925519682447;578.3955766224306;593.1369856858347;613.9017757158833;655.9704813283414;659.4427708586237;687.6630451078935;693.9236683964759;8794.296456283482;9090.727278181665;9252.074792520694;9501.429914205031;9831.901680983241;661.2924171136933;676.1750664189681;694.9583025018516;710.1761956130863;730.8903664450269;4070.898227544335;4219.578042195756;4229.492460904711;4236.809343579546;4323.450829545408;59762;60518.0922286166;60751.392486075456;61225.38774612286;61357.69321153365;46114.538854611696;46292.537074629494;51097.48902510927;51851.74074129605;53428;49418;50374.49625503698;51100.74449627802;51451.48548514467;52403.73798130985;8892.866607000771;9214.354995150275;9464.95267523658;9501.667441639509;9613.423194608346;34924;35496;35709.1072723184;36052.09869753277;36636.45045324269;43315.78342108269;44836;46434.53565464437;47073;47109.52890471051;10449.738756531147;10576.15390768752;10773.838392424277;10964.506597202986;11096.889031109586;12349.444275007825;12430.067744918986;12472.625821225378;12545.686357841127;13005.934970325088;11388.487518061542;11557.88442115568;11654.30074195551;11878.109141814226;12047.216930899463;11121.999505073001;11279.15406344511;11362.602308919479;11462.942685286855;11947.581834635746;10901.400422976812;10945.343279403261;11205.215634905479;11282.266652667551;11610.516765207321;9964.800703986024;9972.850407244043;10084.899151008396;10142.137918277029;10454.477276136315;9622.230221582397;9687.51562421862;9693.273004524548;10177.440240786531;10212.897871021194 05:48:06 INFO - 2015-12-18 05:48:06,687 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:48:06 INFO - 2015-12-18 05:48:06,687 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:48:06 INFO - 2015-12-18 05:48:06,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,300 05:48:06 INFO - 2015-12-18 05:48:06,687 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:48:06 INFO - 2015-12-18 05:48:06,688 DEBUG : BROWSER_OUTPUT: __startTimestamp1450446486674__endTimestamp 05:48:06 INFO - 2015-12-18 05:48:06,688 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,688 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:48:06 INFO - 2015-12-18 05:48:06,688 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:48:06 INFO - 2015-12-18 05:48:06,688 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,688 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5524.53 Median:5738.05 stddev:629.47 (11.0%) stddev-sans-first:604.95 05:48:06 INFO - 2015-12-18 05:48:06,689 DEBUG : BROWSER_OUTPUT: Values: 3223.3 3239.3 3243.4 3265.1 3304.3 5379.3 5519.7 5534.9 5543.2 5596.6 5394.4 5443.8 5460.3 5545.0 5549.6 4889.6 5020.0 5052.6 5076.1 5093.4 5047.9 5092.6 5159.6 5207.3 5210.7 4869.2 4978.4 5088.0 5099.7 5188.2 4959.5 5039.3 5042.1 5053.3 5054.5 4960.7 4974.9 4976.0 4978.9 4984.0 5084.3 5197.4 5245.0 5290.7 5328.4 5872.4 5888.4 5915.8 5931.4 5962.3 5897.1 5935.6 5958.8 5965.5 5965.7 5530.1 5537.7 5541.9 5542.8 5556.3 5822.7 5959.3 6023.7 6115.9 6128.7 5758.3 5867.3 5927.9 5928.9 5955.6 5838.9 5920.8 6003.3 6013.5 6020.0 5786.4 5873.6 5875.3 5914.8 5924.9 5865.2 6003.6 6043.9 6055.3 6059.1 4497.0 4575.1 4624.9 4648.6 4670.9 3983.1 4045.0 4045.9 4073.5 4104.7 5646.7 5655.0 5680.7 5695.1 5696.3 5924.8 6012.0 6044.0 6049.1 6073.7 5703.9 5710.1 5721.5 5747.0 5751.7 5230.9 5235.8 5239.0 5242.2 5291.9 5935.9 6013.8 6058.6 6148.4 6167.2 6117.8 6125.8 6136.4 6137.9 6144.8 6116.5 6180.4 6222.7 6243.3 6247.3 5751.8 5850.6 5890.8 5973.2 5992.3 5994.9 6027.9 6037.9 6040.9 6044.3 5863.9 5867.5 5869.3 5885.8 5889.3 5621.3 5693.3 5788.8 5792.4 5860.3 5652.6 5685.4 5697.8 5710.1 5799.5 5753.2 5763.4 5826.2 5836.2 5842.3 5579.1 5664.2 5692.4 5738.1 5781.1 05:48:06 INFO - 2015-12-18 05:48:06,689 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,689 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45251.93 Median:35751.55 stddev:74613.56 (208.7%) stddev-sans-first:74747.43 05:48:06 INFO - 2015-12-18 05:48:06,689 DEBUG : BROWSER_OUTPUT: Values: 564.5 616.6 621.1 621.3 624.1 35488.8 35829.3 36040.0 36456.0 36491.8 38710.8 38777.0 39044.8 39125.8 39255.0 8494.1 8661.9 8674.0 8683.3 8689.4 8449.4 8493.4 8561.3 8718.3 8723.7 8685.9 8697.7 8743.2 8749.6 8766.2 8559.2 8647.9 8656.3 8678.2 8683.2 8599.2 8678.9 8703.5 8706.8 8731.1 39530.6 39567.4 39622.4 39622.4 39788.8 2991.2 3000.6 3039.8 3082.2 3091.7 29110.9 29124.9 29151.9 29318.9 29513.4 3711.3 3715.0 3852.8 3858.9 3918.8 261273.0 262473.0 262647.0 263217.0 263311.0 55027.7 56017.4 56938.4 57014.4 57219.0 62073.0 62629.0 62943.7 63105.7 63194.0 12816.9 12911.5 12936.1 12969.7 13043.1 9438.7 9445.0 9475.8 9479.4 9494.8 19185.3 19405.6 19420.7 19464.7 19514.7 7240.0 7348.9 7376.6 7428.6 7470.9 965.8 973.2 1065.0 1076.5 1100.2 27869.7 29237.6 29415.0 30042.0 30076.7 1017.8 1037.6 1049.4 1090.4 1095.7 14014.4 14050.5 14648.9 14660.6 15333.9 302397.0 303296.0 303859.0 304223.0 306256.0 52179.5 52334.0 52432.5 52516.7 52603.0 1361.1 1363.5 1375.3 1376.6 1379.6 27791.3 28028.9 28512.9 28969.4 28994.4 45726.8 45869.8 45960.5 46107.5 46270.0 287321.0 288097.0 288681.0 289170.0 289510.0 30049.6 30255.0 30385.5 30649.1 30650.7 41807.8 42106.8 42274.2 42330.2 42877.8 35004.9 35403.6 35464.5 36205.3 36843.4 35080.8 35316.0 35567.5 35628.6 35790.3 34294.8 35514.3 35654.8 35663.3 35712.8 29917.9 32656.7 32839.2 33146.0 33221.2 24292.4 24660.4 25108.9 25455.6 25466.6 05:48:06 INFO - 2015-12-18 05:48:06,690 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,690 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28819.80 Median:4385.52 stddev:90009.14 (2052.4%) stddev-sans-first:90236.28 05:48:06 INFO - 2015-12-18 05:48:06,690 DEBUG : BROWSER_OUTPUT: Values: 361.6 363.3 363.5 364.2 368.5 16300.1 17240.8 17415.7 17856.2 17971.5 13953.2 13993.1 14045.2 14084.6 14209.3 4442.2 4762.8 4918.3 4955.3 4976.3 4896.8 4923.1 4948.8 4957.2 4967.2 4820.1 4935.3 4978.8 5089.4 5109.4 5055.5 5064.7 5066.8 5079.2 5125.5 5110.8 5132.8 5133.8 5135.4 5147.4 12375.8 12549.1 17213.5 20049.3 20229.7 2143.5 2148.9 2158.0 2184.2 2246.5 9431.2 9554.9 9558.8 9676.1 10173.7 1827.8 1840.2 1864.6 1873.3 1886.2 59760.7 62790.0 63279.4 63657.4 63854.0 9949.8 10170.5 10282.6 10370.6 10381.2 13417.9 13503.8 13616.4 13638.7 13672.2 7037.2 7111.2 7117.4 7194.5 7195.2 6724.8 6725.8 6741.4 6745.5 6777.0 4318.2 4368.8 4381.2 4389.9 4400.5 1764.4 1769.5 1838.6 1875.4 1897.9 423.4 433.6 438.3 452.7 472.9 9998.5 11106.3 11298.2 11309.4 11390.4 455.7 460.6 476.9 482.9 490.0 5964.5 6040.9 6053.8 6081.3 6144.1 527297.0 542425.0 551774.0 554345.0 571571.0 83044.6 84187.0 84588.0 84590.2 85027.6 478.3 478.7 480.1 480.9 482.5 10441.7 10603.2 14656.9 14688.2 15046.8 34269.3 35392.1 35561.8 36372.0 36439.8 42375.4 45720.5 52022.0 52077.5 53179.5 9407.6 9679.6 9737.7 9741.0 9750.6 11032.6 11112.2 17610.0 18150.8 18281.9 14663.7 14801.0 15020.9 15312.1 15354.8 14807.6 14812.7 15029.4 15045.0 15552.2 14723.3 14867.1 15006.8 15442.6 15458.9 9446.8 9597.4 9704.7 9748.6 9758.7 13102.4 14315.3 14529.3 14677.9 14881.5 05:48:06 INFO - 2015-12-18 05:48:06,690 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,690 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5271.30 Median:6128.66 stddev:2703.04 (44.1%) stddev-sans-first:2695.63 05:48:06 INFO - 2015-12-18 05:48:06,691 DEBUG : BROWSER_OUTPUT: Values: 1482.9 1742.8 1802.7 1805.0 1813.9 6438.4 6610.8 7343.6 7855.7 7862.6 7359.5 7517.9 7528.1 7530.3 7536.6 2231.0 2266.8 2268.4 2272.7 2276.6 2224.7 2271.7 2338.5 2344.1 2344.4 2267.1 2268.3 2293.3 2304.8 2305.7 2150.3 2180.2 2186.5 2215.7 2226.6 2275.6 2323.0 2327.4 2328.3 2341.4 7419.8 7463.1 7493.4 7498.5 7510.4 4567.4 4625.5 4636.5 4649.4 4679.3 2988.4 2998.5 3053.5 3075.5 3102.3 963.8 965.3 993.7 1015.5 1018.0 10178.1 10315.1 10333.7 10333.8 10351.4 4205.2 4228.4 4253.1 4256.1 4266.0 8990.6 9061.2 9062.3 9063.1 9074.7 4640.0 4645.1 4676.9 4679.9 4695.8 4077.1 4203.2 4222.5 4228.9 4259.2 3516.7 3517.6 3572.9 3588.4 3592.6 1077.5 1080.1 1087.8 1090.1 1092.4 1076.0 1079.1 1087.8 1249.5 1363.3 7140.3 7225.4 7241.6 7257.4 7292.6 492.2 497.5 499.0 505.4 505.5 5538.9 5539.2 5562.1 5563.0 5563.2 8614.8 8773.2 8868.0 8886.5 8930.0 8697.7 8717.5 8766.2 8770.3 8847.6 7095.4 7132.3 7137.5 7210.4 7223.8 6980.9 7005.6 7020.6 7043.7 7047.0 7769.6 7830.5 7862.3 7869.6 7997.8 8072.2 8083.5 8136.6 8148.1 8203.9 6438.4 6683.2 6690.5 6753.4 6805.5 7044.4 7140.0 7140.5 7150.5 7174.4 6770.5 6854.9 6857.6 6903.2 6909.9 6711.1 6714.5 6768.6 6871.6 6875.8 6644.4 6677.0 6708.8 6728.6 6729.3 6198.9 6245.3 6251.2 6254.2 6256.8 6086.9 6099.3 6116.2 6128.1 6129.3 05:48:06 INFO - 2015-12-18 05:48:06,691 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,691 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5578.24 Median:6316.36 stddev:1723.20 (27.3%) stddev-sans-first:1682.51 05:48:06 INFO - 2015-12-18 05:48:06,691 DEBUG : BROWSER_OUTPUT: Values: 335.7 335.9 341.6 342.8 346.6 6281.2 6294.8 6305.0 6330.2 6469.5 6269.7 6275.3 6280.7 6287.5 6309.7 4707.6 4713.1 4730.0 4736.5 4739.7 4731.3 4740.3 4747.8 4750.4 4754.1 4583.3 4646.6 4662.1 4664.7 4675.4 4651.0 4651.4 4656.8 4661.9 4665.9 4014.9 4027.3 4029.5 4035.2 4043.7 5992.9 6002.8 6005.4 6011.0 6024.4 5327.7 5415.5 5456.3 5484.5 5487.3 6642.1 6676.9 6679.4 6682.7 6683.2 4469.8 4498.8 4502.5 4507.5 4512.0 6797.3 6819.9 6850.2 6908.0 6968.1 4641.2 4643.4 4650.5 4651.5 4663.1 4702.3 4708.5 4720.2 4725.9 4729.5 5481.1 5481.7 5481.9 5483.2 5491.6 6576.0 6711.4 6719.9 6742.8 6770.5 1624.8 1633.0 1663.7 1664.3 1666.0 1096.1 1105.7 1111.7 1114.0 1122.2 6298.1 6305.1 6323.0 6372.7 6391.3 6808.2 6843.1 6844.7 6864.8 6873.8 6293.8 6354.0 6361.6 6362.8 6374.3 3770.0 3803.4 3810.6 3816.2 3817.6 7148.4 7151.0 7151.8 7171.4 7177.2 7061.0 7226.9 7235.8 7238.5 7256.7 7169.0 7171.5 7173.1 7179.8 7181.1 7019.8 7028.1 7028.1 7031.2 7034.8 6777.2 6938.2 6947.7 6953.5 6957.1 7109.6 7132.9 7136.4 7141.9 7152.0 4559.4 4592.3 4596.7 4603.0 4604.2 6864.1 6929.9 6980.2 6983.8 6994.2 6790.7 6826.3 6828.4 6829.1 6850.9 6726.6 6740.1 6776.3 6811.4 6826.8 6513.6 6655.9 6692.1 6701.6 6703.5 6838.4 6843.4 6845.6 6846.7 6858.8 6640.4 6650.0 6656.8 6665.1 6674.9 05:48:06 INFO - 2015-12-18 05:48:06,691 DEBUG : BROWSER_OUTPUT: 05:48:06 INFO - 2015-12-18 05:48:06,691 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13874.80 Median:48263.76 stddev:16305.58 (33.8%) stddev-sans-first:16321.89 05:48:06 INFO - 2015-12-18 05:48:06,693 DEBUG : BROWSER_OUTPUT: Values: 828.7 842.2 845.5 847.8 858.1 13230.9 13371.9 13396.7 13544.1 13577.7 12472.4 12667.6 12690.2 12723.1 13150.7 824.3 833.7 833.9 836.6 842.6 831.5 840.5 840.8 843.0 844.6 870.3 871.9 875.7 877.4 879.2 871.1 873.9 876.3 877.2 877.4 639.4 642.4 644.3 645.6 646.7 12192.9 12235.9 12297.3 12390.8 12430.4 10578.4 10640.5 10811.6 10888.9 10937.1 9375.8 9451.3 9583.9 9624.9 9785.2 3528.3 3583.4 3595.5 3606.2 3609.0 42460.2 43292.1 44949.8 45109.3 50557.0 9107.3 9131.1 9148.5 9250.3 9347.0 17062.1 17081.6 17199.3 17241.7 17432.7 7508.9 7612.4 7621.5 7674.7 7997.3 4289.8 4325.4 4348.7 4393.5 4452.9 3051.8 3079.4 3080.6 3081.0 3086.4 562.4 568.2 572.9 578.4 593.1 613.9 656.0 659.4 687.7 693.9 8794.3 9090.7 9252.1 9501.4 9831.9 661.3 676.2 695.0 710.2 730.9 4070.9 4219.6 4229.5 4236.8 4323.5 59762.0 60518.1 60751.4 61225.4 61357.7 46114.5 46292.5 51097.5 51851.7 53428.0 49418.0 50374.5 51100.7 51451.5 52403.7 8892.9 9214.4 9465.0 9501.7 9613.4 34924.0 35496.0 35709.1 36052.1 36636.5 43315.8 44836.0 46434.5 47073.0 47109.5 10449.7 10576.2 10773.8 10964.5 11096.9 12349.4 12430.1 12472.6 12545.7 13005.9 11388.5 11557.9 11654.3 11878.1 12047.2 11122.0 11279.2 11362.6 11462.9 11947.6 10901.4 10945.3 11205.2 11282.3 11610.5 9964.8 9972.9 10084.9 10142.1 10454.5 9622.2 9687.5 9693.3 10177.4 10212.9 05:48:06 INFO - 2015-12-18 05:48:06,693 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:48:06 INFO - 2015-12-18 05:48:06,693 DEBUG : BROWSER_OUTPUT: 05:48:07 INFO - 2015-12-18 05:48:07,376 INFO : Browser exited with error code: 0 05:48:07 INFO - 2015-12-18 05:48:07,392 INFO : Completed test dromaeo_css (00:18:30) 05:48:07 INFO - 2015-12-18 05:48:07,392 INFO : Starting test kraken 05:48:07 INFO - 2015-12-18 05:48:07,393 DEBUG : operating with platform_type : linux_ 05:48:07 INFO - 2015-12-18 05:48:07,393 INFO : Initialising browser for kraken test... 05:48:07 INFO - 2015-12-18 05:48:07,399 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4ffLcj/profile http://localhost:49446/getInfo.html 05:48:11 INFO - 2015-12-18 05:48:11,580 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:48:11 INFO - 2015-12-18 05:48:11,581 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:48:11 INFO - 2015-12-18 05:48:11,581 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:48:11 INFO - 2015-12-18 05:48:11,581 DEBUG : BROWSER_OUTPUT: __metrics 05:48:11 INFO - 2015-12-18 05:48:11,702 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:48:12 INFO - 2015-12-18 05:48:12,620 INFO : Browser initialized. 05:48:12 INFO - 2015-12-18 05:48:12,620 INFO : Running cycle 1/1 for kraken test... 05:48:12 INFO - 2015-12-18 05:48:12,621 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4ffLcj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:48:24 INFO - 2015-12-18 05:48:24,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 151601152 05:48:24 INFO - 2015-12-18 05:48:24,462 DEBUG : BROWSER_OUTPUT: 05:48:26 INFO - 2015-12-18 05:48:26,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:27 INFO - 2015-12-18 05:48:27,567 DEBUG : BROWSER_OUTPUT: RSS: Main: 152432640 05:48:27 INFO - 2015-12-18 05:48:27,567 DEBUG : BROWSER_OUTPUT: 05:48:30 INFO - 2015-12-18 05:48:30,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-dft) 05:48:30 INFO - 2015-12-18 05:48:30,773 DEBUG : BROWSER_OUTPUT: RSS: Main: 152166400 05:48:30 INFO - 2015-12-18 05:48:30,774 DEBUG : BROWSER_OUTPUT: 05:48:33 INFO - 2015-12-18 05:48:33,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-fft) 05:48:34 INFO - 2015-12-18 05:48:34,218 DEBUG : BROWSER_OUTPUT: RSS: Main: 145289216 05:48:34 INFO - 2015-12-18 05:48:34,218 DEBUG : BROWSER_OUTPUT: 05:48:36 INFO - 2015-12-18 05:48:36,218 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-oscillator) 05:48:36 INFO - 2015-12-18 05:48:36,840 DEBUG : BROWSER_OUTPUT: RSS: Main: 146616320 05:48:36 INFO - 2015-12-18 05:48:36,840 DEBUG : BROWSER_OUTPUT: 05:48:39 INFO - 2015-12-18 05:48:39,058 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:39 INFO - 2015-12-18 05:48:39,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 146874368 05:48:39 INFO - 2015-12-18 05:48:39,640 DEBUG : BROWSER_OUTPUT: 05:48:42 INFO - 2015-12-18 05:48:42,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:43 INFO - 2015-12-18 05:48:43,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 146866176 05:48:43 INFO - 2015-12-18 05:48:43,230 DEBUG : BROWSER_OUTPUT: 05:48:46 INFO - 2015-12-18 05:48:46,683 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:47 INFO - 2015-12-18 05:48:47,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 147894272 05:48:47 INFO - 2015-12-18 05:48:47,285 DEBUG : BROWSER_OUTPUT: 05:48:50 INFO - 2015-12-18 05:48:50,104 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49446/tests/kraken/driver.html?testName=json-parse-financial) 05:48:50 INFO - 2015-12-18 05:48:50,705 DEBUG : BROWSER_OUTPUT: RSS: Main: 148320256 05:48:50 INFO - 2015-12-18 05:48:50,705 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-12-18 05:48:52,275 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49446/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:52 INFO - 2015-12-18 05:48:52,856 DEBUG : BROWSER_OUTPUT: RSS: Main: 148987904 05:48:52 INFO - 2015-12-18 05:48:52,856 DEBUG : BROWSER_OUTPUT: 05:48:54 INFO - 2015-12-18 05:48:54,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:55 INFO - 2015-12-18 05:48:55,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 147976192 05:48:55 INFO - 2015-12-18 05:48:55,285 DEBUG : BROWSER_OUTPUT: 05:48:57 INFO - 2015-12-18 05:48:57,365 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:57 INFO - 2015-12-18 05:48:57,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 148553728 05:48:57 INFO - 2015-12-18 05:48:57,967 DEBUG : BROWSER_OUTPUT: 05:49:00 INFO - 2015-12-18 05:49:00,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:01 INFO - 2015-12-18 05:49:01,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 148455424 05:49:01 INFO - 2015-12-18 05:49:01,029 DEBUG : BROWSER_OUTPUT: 05:49:03 INFO - 2015-12-18 05:49:03,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:04 INFO - 2015-12-18 05:49:04,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 149827584 05:49:04 INFO - 2015-12-18 05:49:04,237 DEBUG : BROWSER_OUTPUT: 05:49:05 INFO - 2015-12-18 05:49:05,927 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49446/tests/kraken/driver.html?testName=ai-astar) 05:49:06 INFO - 2015-12-18 05:49:06,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 148262912 05:49:06 INFO - 2015-12-18 05:49:06,177 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,177 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:06 INFO - 2015-12-18 05:49:06,177 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:06 INFO - 2015-12-18 05:49:06,177 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:06 INFO - 2015-12-18 05:49:06,177 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:06 INFO - 2015-12-18 05:49:06,177 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;106;109;111;110;123;113;113;112;112 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;124;147;157;127;151;159;126;163;124 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;164;164;160;162;163;166;169;162 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;74;71;72;74;74;131;71;74;72 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;140;93;94;94;139;95;93;134 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;111;111;110;111;111 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;148;148;147;147;147;146;148 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;97;95;97;96;96;97;96;97 05:49:06 INFO - 2015-12-18 05:49:06,178 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;61;63;61;61;61;61;62;61;59;59 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;68;68;68;67;68;67;68;68;69 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;87;85;87;92;86;85;84;86;85 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;125;118;131;122;116;119;121;124;123 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;156;156;154;163;145;155;154;155;143 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;67;67;66;68;69;67;67;69;65 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:06 INFO - 2015-12-18 05:49:06,179 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:49:06 INFO - 2015-12-18 05:49:06,180 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,739 05:49:06 INFO - 2015-12-18 05:49:06,180 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:49:06 INFO - 2015-12-18 05:49:06,180 DEBUG : BROWSER_OUTPUT: __startTimestamp1450446546175__endTimestamp 05:49:06 INFO - 2015-12-18 05:49:06,180 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,187 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:4.38 (3.9%) stddev-sans-first:4.65 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: Values: 112.0 106.0 109.0 111.0 110.0 123.0 113.0 113.0 112.0 112.0 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.70 Median:150.00 stddev:15.76 (10.5%) stddev-sans-first:16.55 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: Values: 149.0 124.0 147.0 157.0 127.0 151.0 159.0 126.0 163.0 124.0 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.51 (1.5%) stddev-sans-first:2.65 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 164.0 164.0 160.0 162.0 163.0 166.0 169.0 162.0 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.60 Median:73.50 stddev:18.45 (25.1%) stddev-sans-first:19.46 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: Values: 73.0 74.0 71.0 72.0 74.0 74.0 131.0 71.0 74.0 72.0 05:49:06 INFO - 2015-12-18 05:49:06,188 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.90 Median:93.50 stddev:21.29 (22.8%) stddev-sans-first:21.98 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 140.0 93.0 94.0 94.0 139.0 95.0 93.0 134.0 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.40 Median:110.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 111.0 111.0 110.0 111.0 111.0 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 148.0 148.0 147.0 147.0 147.0 146.0 148.0 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 97.0 95.0 97.0 96.0 96.0 97.0 96.0 97.0 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:60.90 Median:61.00 stddev:1.20 (2.0%) stddev-sans-first:1.27 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: Values: 61.0 63.0 61.0 61.0 61.0 61.0 62.0 61.0 59.0 59.0 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,189 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 68.0 68.0 67.0 68.0 67.0 68.0 68.0 69.0 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.00 stddev:2.25 (2.6%) stddev-sans-first:2.35 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: Values: 85.0 87.0 85.0 87.0 92.0 86.0 85.0 84.0 86.0 85.0 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:122.50 stddev:4.19 (3.4%) stddev-sans-first:4.43 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: Values: 121.0 125.0 118.0 131.0 122.0 116.0 119.0 121.0 124.0 123.0 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:155.00 stddev:6.42 (4.1%) stddev-sans-first:6.02 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: Values: 144.0 156.0 156.0 154.0 163.0 145.0 155.0 154.0 155.0 143.0 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 67.0 66.0 68.0 69.0 67.0 67.0 69.0 65.0 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:06 INFO - 2015-12-18 05:49:06,190 DEBUG : BROWSER_OUTPUT: 05:49:07 INFO - 2015-12-18 05:49:07,136 INFO : Browser exited with error code: 0 05:49:07 INFO - 2015-12-18 05:49:07,185 INFO : Completed test kraken (00:00:59) 05:49:07 INFO - 2015-12-18 05:49:07,185 INFO : Starting test v8_7 05:49:07 INFO - 2015-12-18 05:49:07,185 DEBUG : operating with platform_type : linux_ 05:49:07 INFO - 2015-12-18 05:49:07,185 INFO : Initialising browser for v8_7 test... 05:49:07 INFO - 2015-12-18 05:49:07,191 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpma0Bwa/profile http://localhost:49446/getInfo.html 05:49:11 INFO - 2015-12-18 05:49:11,509 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:49:11 INFO - 2015-12-18 05:49:11,509 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:49:11 INFO - 2015-12-18 05:49:11,509 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:49:11 INFO - 2015-12-18 05:49:11,509 DEBUG : BROWSER_OUTPUT: __metrics 05:49:11 INFO - 2015-12-18 05:49:11,625 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:49:12 INFO - 2015-12-18 05:49:12,480 INFO : Browser initialized. 05:49:12 INFO - 2015-12-18 05:49:12,480 INFO : Running cycle 1/1 for v8_7 test... 05:49:12 INFO - 2015-12-18 05:49:12,481 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpma0Bwa/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:49:24 INFO - 2015-12-18 05:49:24,303 DEBUG : BROWSER_OUTPUT: RSS: Main: 150974464 05:49:24 INFO - 2015-12-18 05:49:24,303 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,691 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49446/tests/v8_7/run.html (next: http://localhost:49446/tests/v8_7/run.html) 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: RSS: Main: 146542592 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: |0;Richards;165.26194017517767 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.1821603312006 05:49:45 INFO - 2015-12-18 05:49:45,942 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.79854504756577 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1046.0251046025105 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |5;Earley;740.1924500370096 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |6;Boyer;11788.235294117647 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |7;RegExp;27648.64864864865 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |8;Splay;1489.6142433234422 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: __startTimestamp1450446585940__endTimestamp 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,943 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.26 Median:165.26 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: Values: 165.3 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.18 Median:188.18 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: Values: 188.2 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.80 Median:279.80 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: Values: 279.8 05:49:45 INFO - 2015-12-18 05:49:45,944 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: Values: 5202.1 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1046.03 Median:1046.03 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: Values: 1046.0 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:740.19 Median:740.19 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: Values: 740.2 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11788.24 Median:11788.24 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: Values: 11788.2 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,945 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27648.65 Median:27648.65 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: Values: 27648.6 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:1489.61 Median:1489.61 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: Values: 1489.6 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: Values: 4830.9 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:45 INFO - 2015-12-18 05:49:45,946 DEBUG : BROWSER_OUTPUT: 05:49:46 INFO - 2015-12-18 05:49:46,780 INFO : Browser exited with error code: 0 05:49:46 INFO - 2015-12-18 05:49:46,795 INFO : Completed test v8_7 (00:00:39) 05:49:46 INFO - 2015-12-18 05:49:46,940 INFO : Completed test suite (00:20:09) 05:49:46 INFO - 2015-12-18 05:49:46,940 DEBUG : Working with test: dromaeo_css 05:49:46 INFO - 2015-12-18 05:49:46,940 DEBUG : Generating results file: dromaeo_css 05:49:46 INFO - 2015-12-18 05:49:46,941 DEBUG : Working with test: kraken 05:49:46 INFO - 2015-12-18 05:49:46,941 DEBUG : Generating results file: kraken 05:49:46 INFO - 2015-12-18 05:49:46,942 INFO : javascript benchmark 05:49:46 INFO - 2015-12-18 05:49:46,942 DEBUG : Working with test: v8_7 05:49:46 INFO - 2015-12-18 05:49:46,942 DEBUG : Generating results file: v8_7 05:49:46 INFO - 2015-12-18 05:49:46,942 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:47 INFO - 2015-12-18 05:49:47,004 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 05:49:47 INFO - 2015-12-18 05:49:47,004 DEBUG : process_Request line: dromaeo_css 6899.61 graph.html#tests=[[72,1,43]] 05:49:47 INFO - 2015-12-18 05:49:47,004 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:47 INFO - 2015-12-18 05:49:47,033 DEBUG : process_Request line: kraken 1514.20 graph.html#tests=[[232,1,43]] 05:49:47 INFO - 2015-12-18 05:49:47,033 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:47 INFO - 2015-12-18 05:49:47,060 DEBUG : process_Request line: v8_7 19423.23 graph.html#tests=[[230,1,43]] 05:49:47 INFO - 2015-12-18 05:49:47,062 INFO : javascript benchmark 05:49:47 INFO - 2015-12-18 05:49:47,065 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3223.2586505103827, 3239.2873567815086, 3243.4486137356653, 3265.085775982726, 3304.2895777407884, 5379.273798037264, 5519.668819870811, 5534.944650553493, 5543.168524721289, 5596.608237423382, 5394.379646340672, 5443.8094666686675, 5460.290162278908, 5545.029619816536, 5549.5837812164045, 4889.633277504184, 5020.0, 5052.6210534209895, 5076.060928728197, 5093.363329583802, 5047.924281135786, 5092.618053645972, 5159.58723302135, 5207.3490813648295, 5210.687358758468, 4869.172240719086, 4978.377702787152, 5088.0, 5099.694018358873, 5188.221766734982, 4959.504049595048, 5039.269305950654, 5042.067217564762, 5053.343065401475, 5054.519820617035, 4960.727160006199, 4974.925376119361, 4975.975120124413, 4978.925316120261, 4984.003199360142, 5084.313617661643, 5197.350331208599, 5244.973775131138, 5290.709011004393, 5328.413874473805, 5872.354041055481, 5888.381719919433, 5915.792947246869, 5931.436513531207, 5962.314333851619, 5897.14491398749, 5935.584509084366, 5958.821235362946, 5965.4929331006915, 5965.731542080617, 5530.087535556629, 5537.667739935577, 5541.9168712469345, 5542.778288868481, 5556.333240011184, 5822.650640961556, 5959.255093113361, 6023.668698221552, 6115.9401541424295, 6128.686460748315, 5758.3090029196765, 5867.325257595346, 5927.9407205928255, 5928.851778705481, 5955.583109182316, 5838.8540286492325, 5920.79277225295, 6003.279606447254, 6013.51891848651, 6019.969900150471, 5786.392428794916, 5873.618214816023, 5875.265591801025, 5914.822555323347, 5924.911126333109, 5865.20819689345, 6003.649178934705, 6043.942310095804, 6055.273367195965, 6059.060845568855, 4496.955030449719, 4575.130725162208, 4624.884377890579, 4648.604868586141, 4670.883227919329, 3983.1038016446646, 4045.0, 4045.8786236412957, 4073.5315438724333, 4104.712670113064, 5646.689432081275, 5655.0, 5680.6591604503865, 5695.088785794336, 5696.287964004499, 5924.822255332347, 6011.969940150271, 6044.0, 6049.122877182784, 6073.726682299307, 5703.885922281531, 5710.086386178275, 5721.485066344049, 5747.0, 5751.741171647286, 5230.895382092337, 5235.764390602432, 5239.030779305841, 5242.16125419931, 5291.920621190685, 5935.851603709942, 6013.849653758603, 6058.575899687068, 6148.44663980244, 6167.167432396659, 6117.816465506042, 6125.754969801158, 6136.447719705248, 6137.877242455036, 6144.846378840565, 6116.480099191618, 6180.3798098957595, 6222.719977601109, 6243.313235544178, 6247.344028877084, 5751.769929202785, 5850.619709718854, 5890.764369425175, 5973.163757073926, 5992.340842507234, 5994.910076348771, 6027.909581356195, 6037.879242415215, 6040.879182416415, 6044.304904935989, 5863.882722345615, 5867.501262392744, 5869.325027621708, 5885.823425297248, 5889.322727886177, 5621.325440947113, 5693.3168019837885, 5788.797392091256, 5792.391798861059, 5860.267466566679, 5652.604317697722, 5685.431456854281, 5697.7720891165545, 5710.114932185518, 5799.4780469757925, 5753.165790960371, 5763.423657634203, 5826.155207494974, 5836.212111364912, 5842.269716285465, 5579.079451890392, 5664.178694089416, 5692.373838877803, 5738.053221218453, 5781.075027995669], "unit": "score", "name": "dojo.html", "value": 5482.36965583287}, {"lowerIsBetter": false, "replicates": [564.4834975996963, 616.6454288783949, 621.1490258346066, 621.3320680268711, 624.0763669768745, 35488.822555887215, 35829.2834143317, 36040.0, 36456.0, 36491.81754091229, 38710.8064459678, 38777.0, 39044.804775976154, 39125.80437097818, 39255.0, 8494.108118647546, 8661.913380866205, 8674.04585495595, 8683.30533557317, 8689.435186712843, 8449.408541402105, 8493.36299777516, 8561.315094792433, 8718.258947989396, 8723.651053957834, 8685.913140868573, 8697.652093916267, 8743.213110820025, 8749.562521873913, 8766.167214114648, 8559.186877246651, 8647.870281945776, 8656.307495400415, 8678.175573320525, 8683.218510334069, 8599.226069653698, 8678.913210867873, 8703.52130632815, 8706.782330441727, 8731.126887311282, 39530.60469395298, 39567.40648890269, 39622.405663915066, 39622.405663915066, 39788.80105599453, 2991.222282206642, 3000.564918086887, 3039.7720170987045, 3082.22944263934, 3091.655130018449, 29110.854445727848, 29124.854375727984, 29151.854240728873, 29318.853405733047, 29513.40973180546, 3711.31340701971, 3714.99695082329, 3852.768833869977, 3858.884233473, 3918.8236529356245, 261273.0, 262473.0, 262647.0, 263217.0, 263311.0, 55027.724861375435, 56017.43982560122, 56938.430615693316, 57014.42985570174, 57219.0, 62073.0, 62629.0, 62943.6852815733, 63105.684471578264, 63194.0, 12816.871831281567, 12911.5480958166, 12936.094473386773, 12969.740605187844, 13043.086983911226, 9438.716838494718, 9445.0, 9475.810483790285, 9479.383840050376, 9494.810103798023, 19185.32851350196, 19405.611887762167, 19420.708689369672, 19464.70802937957, 19514.70727939082, 7240.031587049284, 7348.853022939511, 7376.557406555624, 7428.62856857155, 7470.887936680954, 965.7778710896392, 973.1873885305849, 1064.9787004259872, 1076.5263284155103, 1100.1583788401879, 27869.72130278671, 29237.561436578468, 29415.0, 30042.0, 30076.69923300783, 1017.7506510904876, 1037.6160820496314, 1049.4018409506507, 1090.4166271044953, 1095.6548687163518, 14014.439422422989, 14050.508232211823, 14648.926755366369, 14660.633484162981, 15333.923330383499, 302397.0, 303296.0, 303859.0, 304223.0, 306256.0, 52179.47820521746, 52334.0, 52432.47567524352, 52516.73741631267, 52603.0, 1361.1220762608261, 1363.5432130236481, 1375.326090215807, 1376.6351916742071, 1379.5516457151466, 27791.30521736973, 28028.85985570059, 28512.85743571269, 28969.420611588073, 28994.42011159723, 45726.77136614296, 45869.77065114653, 45960.54039459563, 46107.53892461167, 46270.0, 287321.0, 288097.0, 288681.0, 289170.0, 289510.0, 30049.596032316924, 30255.0, 30385.544216836322, 30649.080527584207, 30650.693493064784, 41807.790961045, 42106.789466052476, 42274.15451690887, 42330.15339693127, 42877.78561107175, 35004.89383377014, 35403.64596354003, 35464.46803298004, 36205.28460365536, 36843.447348290334, 35080.82459587686, 35316.0, 35567.46648800322, 35628.64371356253, 35790.28419431545, 34294.828525857214, 35514.28971420609, 35654.821725891205, 35663.28673426465, 35712.821435892656, 29917.850410747807, 32656.673433265365, 32839.17902052468, 33146.0, 33221.16947076342, 24292.392690182885, 24660.383490412165, 25108.874455627603, 25455.61816572716, 25466.61800072963], "unit": "score", "name": "ext.html", "value": 18031.856525016483}, {"lowerIsBetter": false, "replicates": [361.56431500042453, 363.30971154805866, 363.4638907611273, 364.19876272201157, 368.5301240917828, 16300.103494307808, 17240.82759172408, 17415.73876391855, 17856.196471158797, 17971.460856174337, 13953.232572208524, 13993.0904491208, 14045.157290562573, 14084.57746267611, 14209.289535523234, 4442.222611043071, 4762.832716243703, 4918.262260660894, 4955.331030310898, 4976.328195693572, 4896.804127834882, 4923.064617722621, 4948.752562371885, 4957.182064959277, 4967.155583550787, 4820.084184005028, 4935.333729946447, 4978.751062446863, 5089.389273287211, 5109.4379618241965, 5055.519725626078, 5064.696118232918, 5066.847994560169, 5079.212722028092, 5125.513076257749, 5110.8466745997675, 5132.820351287687, 5133.8203162889495, 5135.4094279158, 5147.382314122329, 12375.814362784566, 12549.1215614907, 17213.48359549203, 20049.298274560322, 20229.696554551694, 2143.474848662088, 2148.86032407893, 2157.967630485544, 2184.224600266898, 2246.4608493961496, 9431.245500359955, 9554.856677149848, 9558.808823823554, 9676.080772326688, 10173.694789156338, 1827.7715285589302, 1840.1903162608544, 1864.6270745850884, 1873.297513432463, 1886.1606585069649, 59760.7384517552, 62790.0, 63279.367206327806, 63657.363426365606, 63854.0, 9949.80100397988, 10170.542325595366, 10282.640107596199, 10370.637027703995, 10381.169506439466, 13417.865821341857, 13503.797443038362, 13616.387262573207, 13638.727225455634, 13672.248026358448, 7037.22590515043, 7111.217766045731, 7117.4306055515435, 7194.46041546886, 7195.2444993276, 6724.798256052326, 6725.831854203684, 6741.393274605309, 6745.494087943423, 6777.0, 4318.222719910384, 4368.803403846835, 4381.16757816014, 4389.868303950887, 4400.493943196573, 1764.4089230107802, 1769.4514700442903, 1838.5587459009741, 1875.36237679187, 1897.8671492995634, 423.3945457995097, 433.6119173339843, 438.27465544523864, 452.74872445792244, 472.9361536192639, 9998.500074996222, 11106.278091924, 11298.209125361145, 11309.377984210783, 11390.373529455794, 455.70606958511576, 460.619988509481, 476.86409373328434, 482.9034193161382, 489.99755001224764, 5964.522838172936, 6040.939590604038, 6053.848653783691, 6081.300650425258, 6144.109104179869, 527297.0, 542425.0, 551774.0, 554345.0, 571571.0, 83044.58477707572, 84187.0, 84588.0, 84590.15409845822, 85027.57486212654, 478.2634742496586, 478.67210960492173, 480.0518975024299, 480.8821838649553, 482.54881685623354, 10441.738956526147, 10603.204759642904, 14656.926715366355, 14688.192149431883, 15046.849531504546, 34269.31461370709, 35392.11519712028, 35561.82219088888, 36372.0, 36439.817800911886, 42375.36436953386, 45720.54279457163, 52022.0, 52077.479225207266, 53179.468205319, 9407.576659050495, 9679.564419600994, 9737.707868763948, 9741.025897410202, 9750.561224744763, 11032.613858514913, 11112.166587506132, 17610.0, 18150.818491814913, 18281.908590456962, 14663.70672586521, 14801.037932534351, 15020.94853360298, 15312.081275123528, 15354.769678455055, 14807.555773326818, 14812.703745924806, 15029.398824046915, 15045.0, 15552.222388880284, 14723.33744981457, 14867.107973521192, 15006.774898376314, 15442.613934651723, 15458.922705386402, 9446.811063778548, 9597.376170548892, 9704.660336888175, 9748.610055597981, 9758.707238782565, 13102.410391532594, 14315.28423578796, 14529.273536323353, 14677.853221467649, 14881.47914822976], "unit": "score", "name": "jquery.html", "value": 7979.609014777783}, {"lowerIsBetter": false, "replicates": [1482.9258537073144, 1742.7995780485246, 1802.6755184066872, 1804.9639007219855, 1813.8548916086706, 6438.420542151205, 6610.834729131774, 7343.559386436811, 7855.685772569091, 7862.606869656515, 7359.484836061478, 7517.887231691515, 7528.096628404599, 7530.322270995609, 7536.62316884155, 2230.966535501969, 2266.8073213776847, 2268.4328917770476, 2272.704548408713, 2276.635738281875, 2224.688543603897, 2271.693321401606, 2338.485533182697, 2344.0623750499767, 2344.402177444749, 2267.0705010945517, 2268.262814585258, 2293.266154830455, 2304.7810458006547, 2305.7002589663402, 2150.3333966470286, 2180.2260197629853, 2186.5189658275153, 2215.7481023221826, 2226.6214743493647, 2275.6472746724285, 2323.047550504302, 2327.44141406063, 2328.2549584133085, 2341.4029422497324, 7419.812829947183, 7463.14173870001, 7493.437992150556, 7498.47510674253, 7510.3616192623695, 4567.429071366079, 4625.537446255381, 4636.466806317249, 4649.372334734801, 4679.251319788817, 2988.372441787227, 2998.4902566563737, 3053.4503789317914, 3075.5386691996155, 3102.301982054042, 963.8361478548663, 965.2567523007245, 993.6671215142936, 1015.5125539740966, 1017.9898201018043, 10178.134858537032, 10315.071643552117, 10333.741656458573, 10333.793324133476, 10351.430671313075, 4205.222033923673, 4228.365745138254, 4253.106847561985, 4256.084941737541, 4265.957340426618, 8990.595423205972, 9061.184493395625, 9062.320325975446, 9063.13900179482, 9074.727758167266, 4640.048789998043, 4645.070985802853, 4676.906461870743, 4679.906401871944, 4695.835645752451, 4077.082656402286, 4203.201391735561, 4222.493300803923, 4228.873133805991, 4259.190753756776, 3516.7362447816517, 3517.577890653138, 3572.946405803863, 3588.4258518636893, 3592.5868525119945, 1077.450500244865, 1080.1089101491302, 1087.7770057138268, 1090.1061129873585, 1092.448313601626, 1075.9731006724737, 1079.082779637302, 1087.8259478483405, 1249.506444954238, 1363.277462944641, 7140.321669441395, 7225.421966242688, 7241.637918104074, 7257.419406447471, 7292.635368231673, 492.24194740099847, 497.5472320188611, 498.9850304490871, 505.36576596372055, 505.53490788474693, 5538.944610553843, 5539.169124631257, 5562.137868630369, 5562.972185139049, 5563.165525171175, 8614.827703445895, 8773.166549177819, 8868.0, 8886.511241881759, 8930.0, 8697.652093916298, 8717.476951382938, 8766.167214114648, 8770.254528365032, 8847.601857916408, 7095.432365410755, 7132.251113633098, 7137.500374973702, 7210.4231661468, 7223.783286501413, 6980.860382792417, 7005.614691192033, 7020.5787652741, 7043.7182512700965, 7047.048648432601, 7769.611519423893, 7830.530168189927, 7862.253085956825, 7869.606519673879, 7997.800054998672, 8072.192780721881, 8083.514989100673, 8136.593170341577, 8148.103708592169, 8203.917960820316, 6438.3883531064475, 6683.198016238082, 6690.498212633975, 6753.358430949052, 6805.489588280998, 7044.401225895856, 7140.036095127091, 7140.500164988609, 7150.463715221484, 7174.426045916419, 6770.492213084137, 6854.897176542456, 6857.55425897315, 6903.1716194055025, 6909.93090069113, 6711.127553418024, 6714.52998290115, 6768.593884366954, 6871.553349032297, 6875.7937261882225, 6644.435223006099, 6677.0, 6708.765193218214, 6728.562643428161, 6729.327067293231, 6198.938010619836, 6245.250569931637, 6251.156093927261, 6254.155688981929, 6256.843578910381, 6086.878262434815, 6099.2985806632805, 6116.204893363834, 6128.050152226412, 6129.264488261437], "unit": "score", "name": "mootools.html", "value": 4290.350291337891}, {"lowerIsBetter": false, "replicates": [335.74147906112296, 335.8505465068044, 341.5798567761653, 342.81316682408084, 346.5581383735737, 6281.214848143982, 6294.811155665326, 6304.968475157618, 6330.208723909512, 6469.450096741771, 6269.749210031593, 6275.309715931244, 6280.685965701719, 6287.465565426933, 6309.716062777175, 4707.599854012413, 4713.10451014307, 4730.0, 4736.478987311393, 4739.668223224376, 4731.266653668669, 4740.288956656512, 4747.8338258160975, 4750.406199225097, 4754.072955773626, 4583.335416364625, 4646.605038571709, 4662.137504561667, 4664.696794708333, 4675.438947326308, 4651.0, 4651.395318608593, 4656.767161641938, 4661.906761864744, 4665.9066818663605, 4014.9397759033636, 4027.2549578328103, 4029.5164580250266, 4035.1929614077303, 4043.65628921542, 5992.8501787455225, 6002.759889604411, 6005.429484198994, 6011.0382338825575, 6024.367441418632, 5327.733613319358, 5415.458454154553, 5456.290682211326, 5484.451554844523, 5487.314085739283, 6642.070110184578, 6676.899846502306, 6679.432248258904, 6682.665866706645, 6683.198016238034, 4469.7988590513505, 4498.775061246924, 4502.549745025504, 4507.526709695468, 4511.954880451186, 6797.320267973163, 6819.897701534481, 6850.21222559407, 6908.0, 6968.0941477607585, 4641.234196357631, 4643.373144625432, 4650.51169627191, 4651.534846515321, 4663.137319595886, 4702.341672165831, 4708.458527269339, 4720.221163507983, 4725.8582242532775, 4729.456112547033, 5481.068218402801, 5481.671099734029, 5481.9036192761605, 5483.232347471358, 5491.588130890199, 6575.967120164369, 6711.429528490035, 6719.899201511981, 6742.831429214309, 6770.52606317553, 1624.8212696603364, 1632.959176020609, 1663.7421199713822, 1664.2594045649569, 1665.992074794756, 1096.0792933935531, 1105.745678493951, 1111.74985628233, 1113.9888601114046, 1122.2144498850837, 6298.0552917062805, 6305.054241863757, 6323.0, 6372.681365931685, 6391.265004524539, 6808.2170550386345, 6843.076184715, 6844.691988860513, 6864.828379290558, 6873.793786186422, 6293.779717709858, 6353.96823015882, 6361.554691171574, 6362.809115726535, 6374.330695277022, 3770.019794853303, 3803.4294855771304, 3810.618938106167, 3816.217675376541, 3817.6373244541724, 7148.428125749928, 7151.0, 7151.82120446993, 7171.42628589722, 7177.1746249182015, 7061.046758687614, 7226.891596626055, 7235.819104522429, 7238.457115716343, 7256.7460138894885, 7169.032180655649, 7171.462140339496, 7173.1392232932385, 7179.784606461814, 7181.06646135999, 7019.824504387431, 7028.051213086168, 7028.121484814398, 7031.191412987368, 7034.824129396806, 6777.152855893013, 6938.167419909643, 6947.7220911162985, 6953.548019378724, 6957.060796792571, 7109.573425594481, 7132.857342853217, 7136.357727804522, 7141.892871606826, 7152.0, 4559.361689363425, 4592.334111553872, 4596.724196548218, 4603.033362993675, 4604.240300350405, 6864.107666003388, 6929.930700692928, 6980.162380514371, 6983.825404364931, 6994.160700715946, 6790.694418751069, 6826.283240259701, 6828.419584335387, 6829.146356705412, 6850.8972365413565, 6726.596404215763, 6740.056392105207, 6776.28848970851, 6811.421029212374, 6826.829329266808, 6513.576617520036, 6655.900161497485, 6692.063111164538, 6701.631410272482, 6703.530752847449, 6838.418734407432, 6843.3840954314355, 6845.589264643938, 6846.691898864663, 6858.759943401957, 6640.369164929324, 6650.0, 6656.800295991128, 6665.100211471583, 6674.933250667431], "unit": "score", "name": "prototype.html", "value": 5012.431177937904}, {"lowerIsBetter": false, "replicates": [828.6892415344246, 842.2209456252964, 845.463130911871, 847.8050048488843, 858.1461445861369, 13230.933845330772, 13371.933140334284, 13396.66508337292, 13544.051916365846, 13577.66055848603, 12472.376381180928, 12667.619971400873, 12690.175138615983, 12723.10938234324, 13150.73698526029, 824.3199360527559, 833.7165363776314, 833.8582440985047, 836.5858899844585, 842.5913431985513, 831.5260301628074, 840.537704262653, 840.756180707594, 842.9747107586783, 844.6114787197896, 870.268974061788, 871.860502319629, 875.7022612311813, 877.3770622857746, 879.2438502887512, 871.1201686296854, 873.9126087391273, 876.3077169036517, 877.1535468273147, 877.3551439691821, 639.3829954094322, 642.383312020461, 644.3041515163612, 645.6319897658336, 646.6766616691655, 12192.939035304855, 12235.93882030593, 12297.3236471994, 12390.81413778794, 12430.440630171574, 10578.418186999712, 10640.467976601216, 10811.567537298499, 10888.945555272252, 10937.12502999766, 9375.812483750355, 9451.29115316354, 9583.856242156373, 9624.855627165598, 9785.217182625373, 3528.311979164064, 3583.3549961007006, 3595.514605528246, 3606.1705807664384, 3609.0, 42460.150796983886, 43292.13415731699, 44949.775251123865, 45109.323360149625, 50556.99999999963, 9107.316951228684, 9131.13254240846, 9148.542572871329, 9250.259979201648, 9346.953265233631, 17062.146892655317, 17081.572960675832, 17199.312027518758, 17241.741373879402, 17432.65134697299, 7508.924910750822, 7612.352949999312, 7621.54270743746, 7674.731384401519, 7997.320227780588, 4289.785510724514, 4325.437693099877, 4348.695591308578, 4393.494748104009, 4452.888677783017, 3051.8016328938547, 3079.414911166871, 3080.630324361091, 3081.0, 3086.382723455318, 562.4038519169693, 568.2130249604245, 572.925519682447, 578.3955766224306, 593.1369856858347, 613.9017757158833, 655.9704813283414, 659.4427708586237, 687.6630451078935, 693.9236683964759, 8794.296456283482, 9090.727278181665, 9252.074792520694, 9501.429914205031, 9831.901680983241, 661.2924171136933, 676.1750664189681, 694.9583025018516, 710.1761956130863, 730.8903664450269, 4070.898227544335, 4219.578042195756, 4229.492460904711, 4236.809343579546, 4323.450829545408, 59762.0, 60518.0922286166, 60751.392486075456, 61225.38774612286, 61357.69321153365, 46114.538854611696, 46292.537074629494, 51097.48902510927, 51851.74074129605, 53428.0, 49418.0, 50374.49625503698, 51100.74449627802, 51451.48548514467, 52403.73798130985, 8892.866607000771, 9214.354995150275, 9464.95267523658, 9501.667441639509, 9613.423194608346, 34924.0, 35496.0, 35709.1072723184, 36052.09869753277, 36636.45045324269, 43315.78342108269, 44836.0, 46434.53565464437, 47073.0, 47109.52890471051, 10449.738756531147, 10576.15390768752, 10773.838392424277, 10964.506597202986, 11096.889031109586, 12349.444275007825, 12430.067744918986, 12472.625821225378, 12545.686357841127, 13005.934970325088, 11388.487518061542, 11557.88442115568, 11654.30074195551, 11878.109141814226, 12047.216930899463, 11121.999505073001, 11279.15406344511, 11362.602308919479, 11462.942685286855, 11947.581834635746, 10901.400422976812, 10945.343279403261, 11205.215634905479, 11282.266652667551, 11610.516765207321, 9964.800703986024, 9972.850407244043, 10084.899151008396, 10142.137918277029, 10454.477276136315, 9622.230221582397, 9687.51562421862, 9693.273004524548, 10177.440240786531, 10212.897871021194], "unit": "score", "name": "yui.html", "value": 6358.845852172002}], "name": "dromaeo_css", "value": 6899.60682287478}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 106.0, 109.0, 111.0, 110.0, 123.0, 113.0, 113.0, 112.0, 112.0], "unit": "score", "name": "ai-astar", "value": 112.1}, {"lowerIsBetter": true, "replicates": [149.0, 124.0, 147.0, 157.0, 127.0, 151.0, 159.0, 126.0, 163.0, 124.0], "unit": "score", "name": "audio-beat-detection", "value": 142.7}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 164.0, 164.0, 160.0, 162.0, 163.0, 166.0, 169.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.5}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 71.0, 72.0, 74.0, 74.0, 131.0, 71.0, 74.0, 72.0], "unit": "score", "name": "audio-fft", "value": 78.6}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 140.0, 93.0, 94.0, 94.0, 139.0, 95.0, 93.0, 134.0], "unit": "score", "name": "audio-oscillator", "value": 106.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 111.0, 110.0, 111.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.4}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 148.0, 148.0, 147.0, 147.0, 147.0, 146.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.1}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 97.0, 95.0, 97.0, 96.0, 96.0, 97.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [61.0, 63.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 59.0, 59.0], "unit": "score", "name": "json-parse-financial", "value": 60.9}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 85.0, 87.0, 92.0, 86.0, 85.0, 84.0, 86.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.2}, {"lowerIsBetter": true, "replicates": [121.0, 125.0, 118.0, 131.0, 122.0, 116.0, 119.0, 121.0, 124.0, 123.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.0}, {"lowerIsBetter": true, "replicates": [144.0, 156.0, 156.0, 154.0, 163.0, 145.0, 155.0, 154.0, 155.0, 143.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.5}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 66.0, 68.0, 69.0, 67.0, 67.0, 69.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.2}], "name": "kraken", "value": 1514.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.26194017517767], "unit": "score", "name": "Richards", "value": 213.61240199999997}, {"lowerIsBetter": false, "replicates": [188.1821603312006], "unit": "score", "name": "DeltaBlue", "value": 351.351052}, {"lowerIsBetter": false, "replicates": [279.79854504756577], "unit": "score", "name": "Encrypt", "value": 951.330894}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1046.0251046025105], "unit": "score", "name": "RayTrace", "value": 707.4294840000001}, {"lowerIsBetter": false, "replicates": [740.1924500370096], "unit": "score", "name": "Earley", "value": 900.3915129999997}, {"lowerIsBetter": false, "replicates": [11788.235294117647], "unit": "score", "name": "Boyer", "value": 56.536282435129706}, {"lowerIsBetter": false, "replicates": [27648.64864864865], "unit": "score", "name": "RegExp", "value": 32.94862658846528}, {"lowerIsBetter": false, "replicates": [1489.6142433234422], "unit": "score", "name": "Splay", "value": 54.70610956175298}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19423.232510918086}]} 05:49:47 INFO - RETURN: dromaeo_css: 6899.61 05:49:47 INFO - RETURN: kraken: 1514.20 05:49:47 INFO - RETURN: v8_7: 19423.23 05:49:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "19423.23"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1514.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6899.61"}}} 05:49:47 INFO - Return code: 0 05:49:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:49:47 INFO - # TBPL SUCCESS # 05:49:47 INFO - Running post-action listener: _resource_record_post_action 05:49:47 INFO - Running post-run listener: _resource_record_post_run 05:49:48 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 28672; Write bytes: 557522944; Read time: 116; Write time: 496764 05:49:48 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234643456; Read time: 0; Write time: 280624 05:49:48 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 28672; Write bytes: 322879488; Read time: 116; Write time: 216140 05:49:48 INFO - Running post-run listener: _upload_blobber_files 05:49:48 INFO - Blob upload gear active. 05:49:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:49:48 INFO - Copying logs to upload dir... 05:49:48 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1268.061576 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-12-18 05:49:46.735288) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:46.738512) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009937 build_url: 'https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:46.790351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:46.790683) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450443781.453821-940720548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004650 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:46.839773) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 05:49:46.840114) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 05:49:46.840424) ========= ========= Total master_lag: 0.24 =========