builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1453391175.01 results: success (0) buildid: 20160121051922 builduid: 321dedcc02c547fbbb26220ac70a955c revision: 57a4738271792310f2b061fee8447060b13e961d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.010897) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.011357) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.011905) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006441 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.036050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.036348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.052260) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.052675) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004617 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.103478) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.103794) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.104147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.104418) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-21 07:46:15-- 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% 98.1M=0s 2016-01-21 07:46:15 (98.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164721 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.295643) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.295925) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014564 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.356056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.356403) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 57a4738271792310f2b061fee8447060b13e961d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 57a4738271792310f2b061fee8447060b13e961d --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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-21 07:46:15,522 truncating revision to first 12 chars 2016-01-21 07:46:15,522 Setting DEBUG logging. 2016-01-21 07:46:15,522 attempt 1/10 2016-01-21 07:46:15,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/57a473827179?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-21 07:46:15,768 unpacking tar archive at: mozilla-aurora-57a473827179/testing/mozharness/ program finished with exit code 0 elapsedTime=0.581994 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.952088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:15.952418) ========= echo 57a4738271792310f2b061fee8447060b13e961d in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'57a4738271792310f2b061fee8447060b13e961d'] 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 57a4738271792310f2b061fee8447060b13e961d program finished with exit code 0 elapsedTime=0.004661 script_repo_revision: '57a4738271792310f2b061fee8447060b13e961d' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:16.001285) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:16.001606) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:16.011639) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:16.011992) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/57a4738271792310f2b061fee8447060b13e961d ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-21 07:46:16.012426) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-01-21 07:46:16.012736) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:46:16 INFO - MultiFileLogger online at 20160121 07:46:16 in /builds/slave/test-pgo 07:46:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:46:16 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:46:16 INFO - {'append_to_log': False, 07:46:16 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:46:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:46:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:46:16 INFO - 'branch': 'Mozilla-Aurora', 07:46:16 INFO - 'buildbot_json_path': 'buildprops.json', 07:46:16 INFO - 'config_files': ('talos/linux_config.py',), 07:46:16 INFO - 'default_actions': ('clobber', 07:46:16 INFO - 'read-buildbot-config', 07:46:16 INFO - 'download-and-extract', 07:46:16 INFO - 'populate-webroot', 07:46:16 INFO - 'create-virtualenv', 07:46:16 INFO - 'install', 07:46:16 INFO - 'run-tests'), 07:46:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:46:16 INFO - 'download_minidump_stackwalk': True, 07:46:16 INFO - 'download_symbols': 'ondemand', 07:46:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:46:16 INFO - 'tooltool.py': '/tools/tooltool.py', 07:46:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:46:16 INFO - '/tools/misc-python/virtualenv.py')}, 07:46:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:46:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:46:16 INFO - 'installer_path': 'installer.exe', 07:46:16 INFO - 'log_level': 'info', 07:46:16 INFO - 'log_name': 'talos', 07:46:16 INFO - 'log_to_console': True, 07:46:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:46:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:46:16 INFO - 'opt_config_files': (), 07:46:16 INFO - 'pip_index': False, 07:46:16 INFO - 'sps_profile': False, 07:46:16 INFO - 'sps_profile_interval': 0, 07:46:16 INFO - 'suite': 'dromaeojs', 07:46:16 INFO - 'system_bits': '32', 07:46:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:46:16 INFO - 'title': 'talos-linux64-ix-046', 07:46:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:46:16 INFO - 'use_talos_json': True, 07:46:16 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:46:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:46:16 INFO - 'work_dir': 'build'} 07:46:16 INFO - ##### 07:46:16 INFO - ##### Running clobber step. 07:46:16 INFO - ##### 07:46:16 INFO - Running pre-action listener: _resource_record_pre_action 07:46:16 INFO - Running main action method: clobber 07:46:16 INFO - rmtree: /builds/slave/test-pgo/build 07:46:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:46:16 INFO - Running post-action listener: _resource_record_post_action 07:46:16 INFO - ##### 07:46:16 INFO - ##### Running read-buildbot-config step. 07:46:16 INFO - ##### 07:46:16 INFO - Running pre-action listener: _resource_record_pre_action 07:46:16 INFO - Running main action method: read_buildbot_config 07:46:16 INFO - Using buildbot properties: 07:46:16 INFO - { 07:46:16 INFO - "properties": { 07:46:16 INFO - "buildnumber": 99, 07:46:16 INFO - "product": "firefox", 07:46:16 INFO - "script_repo_revision": "production", 07:46:16 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:46:16 INFO - "repository": "", 07:46:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:46:16 INFO - "buildid": "20160121051922", 07:46:16 INFO - "slavename": "talos-linux64-ix-046", 07:46:16 INFO - "pgo_build": "True", 07:46:16 INFO - "basedir": "/builds/slave/test-pgo", 07:46:16 INFO - "project": "", 07:46:16 INFO - "platform": "ubuntu64_hw", 07:46:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:46:16 INFO - "slavebuilddir": "test", 07:46:16 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:46:16 INFO - "branch": "mozilla-aurora", 07:46:16 INFO - "repo_path": "releases/mozilla-aurora", 07:46:16 INFO - "stage_platform": "linux64-pgo", 07:46:16 INFO - "builduid": "321dedcc02c547fbbb26220ac70a955c", 07:46:16 INFO - "revision": "57a4738271792310f2b061fee8447060b13e961d" 07:46:16 INFO - }, 07:46:16 INFO - "sourcestamp": { 07:46:16 INFO - "repository": "", 07:46:16 INFO - "hasPatch": false, 07:46:16 INFO - "project": "", 07:46:16 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 07:46:16 INFO - "changes": [ 07:46:16 INFO - { 07:46:16 INFO - "category": null, 07:46:16 INFO - "files": [ 07:46:16 INFO - { 07:46:16 INFO - "url": null, 07:46:16 INFO - "name": "https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 07:46:16 INFO - } 07:46:16 INFO - ], 07:46:16 INFO - "repository": "", 07:46:16 INFO - "rev": "57a4738271792310f2b061fee8447060b13e961d", 07:46:16 INFO - "who": "cbook@mozilla.com", 07:46:16 INFO - "when": 1453391149, 07:46:16 INFO - "number": 7493146, 07:46:16 INFO - "comments": "Backed out changeset a812bb3848d0 (bug 1237179) for test failures in VideoPuppeteer Media Tests", 07:46:16 INFO - "project": "", 07:46:16 INFO - "at": "Thu 21 Jan 2016 07:45:49", 07:46:16 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 07:46:16 INFO - "revlink": "", 07:46:16 INFO - "properties": [ 07:46:16 INFO - [ 07:46:16 INFO - "buildid", 07:46:16 INFO - "20160121051922", 07:46:16 INFO - "Change" 07:46:16 INFO - ], 07:46:16 INFO - [ 07:46:16 INFO - "builduid", 07:46:16 INFO - "321dedcc02c547fbbb26220ac70a955c", 07:46:16 INFO - "Change" 07:46:16 INFO - ], 07:46:16 INFO - [ 07:46:16 INFO - "pgo_build", 07:46:16 INFO - "True", 07:46:16 INFO - "Change" 07:46:16 INFO - ] 07:46:16 INFO - ], 07:46:16 INFO - "revision": "57a4738271792310f2b061fee8447060b13e961d" 07:46:16 INFO - } 07:46:16 INFO - ], 07:46:16 INFO - "revision": "57a4738271792310f2b061fee8447060b13e961d" 07:46:16 INFO - } 07:46:16 INFO - } 07:46:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 07:46:16 INFO - Running post-action listener: _resource_record_post_action 07:46:16 INFO - ##### 07:46:16 INFO - ##### Running download-and-extract step. 07:46:16 INFO - ##### 07:46:16 INFO - Running pre-action listener: _resource_record_pre_action 07:46:16 INFO - Running main action method: download_and_extract 07:46:16 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:16 INFO - https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:46:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/test_packages.json 07:46:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/test_packages.json 07:46:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:46:24 INFO - Downloaded 1302 bytes. 07:46:24 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:46:24 INFO - Using the following test package requirements: 07:46:24 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 07:46:24 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:46:24 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'jsshell-linux-x86_64.zip'], 07:46:24 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:46:24 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 07:46:24 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:46:24 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:46:24 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:46:24 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 07:46:24 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 07:46:24 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:46:24 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:24 INFO - https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:46:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:46:27 INFO - Downloaded 23160682 bytes. 07:46:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 07:46:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:46:28 INFO - Return code: 0 07:46:28 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:28 INFO - https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:46:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:46:33 INFO - Downloaded 23160682 bytes. 07:46:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 07:46:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 07:46:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:46:34 INFO - Return code: 0 07:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:34 INFO - https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:46:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 07:46:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 07:46:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 07:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:46:35 INFO - Downloaded 11132038 bytes. 07:46:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 07:46:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 07:46:35 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 07:46:36 INFO - Return code: 0 07:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:36 INFO - https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 07:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 07:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 07:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:46:39 INFO - Downloaded 58133675 bytes. 07:46:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 07:46:39 INFO - mkdir: /builds/slave/test-pgo/properties 07:46:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:46:39 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:46:39 INFO - Contents: 07:46:39 INFO - build_url:https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 07:46:39 INFO - Running post-action listener: _resource_record_post_action 07:46:39 INFO - Running post-action listener: set_extra_try_arguments 07:46:39 INFO - ##### 07:46:39 INFO - ##### Running populate-webroot step. 07:46:39 INFO - ##### 07:46:39 INFO - Running pre-action listener: _resource_record_pre_action 07:46:39 INFO - Running main action method: populate_webroot 07:46:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:46:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:46:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:46:39 INFO - '--tppagecycles', 07:46:39 INFO - '7'], 07:46:39 INFO - 'tests': ['tsvgm']}}, 07:46:39 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:46:39 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:46:39 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:46:39 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:46:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'tests': ['damp', 'tps']}, 07:46:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['damp', 'tps']}, 07:46:39 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:46:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:46:39 INFO - 'other': {'tests': ['a11yr', 07:46:39 INFO - 'ts_paint', 07:46:39 INFO - 'tpaint', 07:46:39 INFO - 'sessionrestore', 07:46:39 INFO - 'sessionrestore_no_auto_restore']}, 07:46:39 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['a11yr', 07:46:39 INFO - 'ts_paint', 07:46:39 INFO - 'tpaint', 07:46:39 INFO - 'sessionrestore', 07:46:39 INFO - 'sessionrestore_no_auto_restore']}, 07:46:39 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['a11yr', 07:46:39 INFO - 'ts_paint', 07:46:39 INFO - 'tpaint', 07:46:39 INFO - 'sessionrestore', 07:46:39 INFO - 'sessionrestore_no_auto_restore']}, 07:46:39 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['a11yr', 07:46:39 INFO - 'ts_paint', 07:46:39 INFO - 'tpaint', 07:46:39 INFO - 'sessionrestore', 07:46:39 INFO - 'sessionrestore_no_auto_restore']}, 07:46:39 INFO - 'other_l64': {'tests': ['a11yr', 07:46:39 INFO - 'ts_paint', 07:46:39 INFO - 'tpaint', 07:46:39 INFO - 'sessionrestore', 07:46:39 INFO - 'sessionrestore_no_auto_restore']}, 07:46:39 INFO - 'other_nol64': {'tests': ['a11yr', 07:46:39 INFO - 'ts_paint', 07:46:39 INFO - 'tpaint', 07:46:39 INFO - 'sessionrestore', 07:46:39 INFO - 'sessionrestore_no_auto_restore']}, 07:46:39 INFO - 'svgr': {'tests': ['tsvgx', 07:46:39 INFO - 'tsvgr_opacity', 07:46:39 INFO - 'tart', 07:46:39 INFO - 'tscrollx', 07:46:39 INFO - 'cart']}, 07:46:39 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['tsvgx', 07:46:39 INFO - 'tsvgr_opacity', 07:46:39 INFO - 'tart', 07:46:39 INFO - 'tscrollx', 07:46:39 INFO - 'cart']}, 07:46:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'tests': ['tp5o']}, 07:46:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'talos_options': ['--e10s'], 07:46:39 INFO - 'tests': ['tp5o']}, 07:46:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'talos_options': ['--xperf_path', 07:46:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:39 INFO - 'tests': ['tp5n']}, 07:46:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:39 INFO - 'talos_options': ['--e10s', 07:46:39 INFO - '--xperf_path', 07:46:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:39 INFO - 'tests': ['tp5n']}}, 07:46:39 INFO - 'talos.zip': {'path': '', 07:46:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:46:39 INFO - Running post-action listener: _resource_record_post_action 07:46:39 INFO - ##### 07:46:39 INFO - ##### Running create-virtualenv step. 07:46:39 INFO - ##### 07:46:39 INFO - Running pre-action listener: _resource_record_pre_action 07:46:39 INFO - Running main action method: create_virtualenv 07:46:39 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:46:39 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 07:46:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:46:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:46:39 INFO - Using real prefix '/usr' 07:46:39 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:46:39 INFO - Installing distribute.............................................................................................................................................................................................done. 07:46:41 INFO - Installing pip.................done. 07:46:41 INFO - Return code: 0 07:46:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:46:41 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')]} 07:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:41 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')]} 07:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:41 INFO - Using env: {'DISPLAY': ':0', 07:46:41 INFO - 'HOME': '/home/cltbld', 07:46:41 INFO - 'LANG': 'en_US.UTF-8', 07:46:41 INFO - 'LANGUAGE': 'en_US:en', 07:46:41 INFO - 'LOGNAME': 'cltbld', 07:46:41 INFO - 'MAIL': '/var/mail/cltbld', 07:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:41 INFO - 'MOZ_NO_REMOTE': '1', 07:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:41 INFO - 'NO_EM_RESTART': '1', 07:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:41 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:41 INFO - 'SHELL': '/bin/bash', 07:46:41 INFO - 'SHLVL': '1', 07:46:41 INFO - 'TERM': 'linux', 07:46:41 INFO - 'TMOUT': '86400', 07:46:41 INFO - 'USER': 'cltbld', 07:46:41 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:41 INFO - '_': '/tools/buildbot/bin/python'} 07:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:41 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 07:46:41 INFO - Cleaning up... 07:46:42 INFO - Return code: 0 07:46:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:42 INFO - Using env: {'DISPLAY': ':0', 07:46:42 INFO - 'HOME': '/home/cltbld', 07:46:42 INFO - 'LANG': 'en_US.UTF-8', 07:46:42 INFO - 'LANGUAGE': 'en_US:en', 07:46:42 INFO - 'LOGNAME': 'cltbld', 07:46:42 INFO - 'MAIL': '/var/mail/cltbld', 07:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:42 INFO - 'MOZ_NO_REMOTE': '1', 07:46:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:42 INFO - 'NO_EM_RESTART': '1', 07:46:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:42 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:42 INFO - 'SHELL': '/bin/bash', 07:46:42 INFO - 'SHLVL': '1', 07:46:42 INFO - 'TERM': 'linux', 07:46:42 INFO - 'TMOUT': '86400', 07:46:42 INFO - 'USER': 'cltbld', 07:46:42 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:42 INFO - '_': '/tools/buildbot/bin/python'} 07:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:42 INFO - Downloading/unpacking psutil>=0.7.1 07:46:42 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 07:46:42 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 07:46:42 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 07:46:42 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 07:46:42 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 07:46:42 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 07:46:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 07:46:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:44 INFO - Installing collected packages: psutil 07:46:44 INFO - Running setup.py install for psutil 07:46:44 INFO - building 'psutil._psutil_linux' extension 07:46:44 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 07:46:44 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 07:46:44 INFO - building 'psutil._psutil_posix' extension 07:46:44 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 07:46:44 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 07:46:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:44 INFO - Successfully installed psutil 07:46:44 INFO - Cleaning up... 07:46:44 INFO - Return code: 0 07:46:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 07:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:44 INFO - Using env: {'DISPLAY': ':0', 07:46:44 INFO - 'HOME': '/home/cltbld', 07:46:44 INFO - 'LANG': 'en_US.UTF-8', 07:46:44 INFO - 'LANGUAGE': 'en_US:en', 07:46:44 INFO - 'LOGNAME': 'cltbld', 07:46:44 INFO - 'MAIL': '/var/mail/cltbld', 07:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:44 INFO - 'MOZ_NO_REMOTE': '1', 07:46:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:44 INFO - 'NO_EM_RESTART': '1', 07:46:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:44 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:44 INFO - 'SHELL': '/bin/bash', 07:46:44 INFO - 'SHLVL': '1', 07:46:44 INFO - 'TERM': 'linux', 07:46:44 INFO - 'TMOUT': '86400', 07:46:44 INFO - 'USER': 'cltbld', 07:46:44 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:44 INFO - '_': '/tools/buildbot/bin/python'} 07:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:46:44 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 07:46:44 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 07:46:44 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 07:46:44 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 07:46:44 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 07:46:44 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 07:46:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:46:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:46:46 INFO - Installing collected packages: mozsystemmonitor 07:46:46 INFO - Running setup.py install for mozsystemmonitor 07:46:46 INFO - Successfully installed mozsystemmonitor 07:46:46 INFO - Cleaning up... 07:46:46 INFO - Return code: 0 07:46:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 07:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:46 INFO - Using env: {'DISPLAY': ':0', 07:46:46 INFO - 'HOME': '/home/cltbld', 07:46:46 INFO - 'LANG': 'en_US.UTF-8', 07:46:46 INFO - 'LANGUAGE': 'en_US:en', 07:46:46 INFO - 'LOGNAME': 'cltbld', 07:46:46 INFO - 'MAIL': '/var/mail/cltbld', 07:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:46 INFO - 'MOZ_NO_REMOTE': '1', 07:46:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:46 INFO - 'NO_EM_RESTART': '1', 07:46:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:46 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:46 INFO - 'SHELL': '/bin/bash', 07:46:46 INFO - 'SHLVL': '1', 07:46:46 INFO - 'TERM': 'linux', 07:46:46 INFO - 'TMOUT': '86400', 07:46:46 INFO - 'USER': 'cltbld', 07:46:46 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:46 INFO - '_': '/tools/buildbot/bin/python'} 07:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:47 INFO - Downloading/unpacking blobuploader==1.2.4 07:46:47 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 07:46:47 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 07:46:47 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 07:46:47 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 07:46:47 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 07:46:47 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 07:46:48 INFO - Downloading blobuploader-1.2.4.tar.gz 07:46:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:46:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 07:46:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 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 07:46:49 INFO - Downloading docopt-0.6.1.tar.gz 07:46:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 07:46:49 INFO - Installing collected packages: blobuploader, requests, docopt 07:46:49 INFO - Running setup.py install for blobuploader 07:46:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:46:49 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 07:46:49 INFO - Running setup.py install for requests 07:46:50 INFO - Running setup.py install for docopt 07:46:50 INFO - Successfully installed blobuploader requests docopt 07:46:50 INFO - Cleaning up... 07:46:50 INFO - Return code: 0 07:46:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:46:50 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')]} 07:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:50 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')]} 07:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 07:46:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 07:46:50 INFO - Using env: {'DISPLAY': ':0', 07:46:50 INFO - 'HOME': '/home/cltbld', 07:46:50 INFO - 'LANG': 'en_US.UTF-8', 07:46:50 INFO - 'LANGUAGE': 'en_US:en', 07:46:50 INFO - 'LOGNAME': 'cltbld', 07:46:50 INFO - 'MAIL': '/var/mail/cltbld', 07:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:50 INFO - 'MOZ_NO_REMOTE': '1', 07:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:50 INFO - 'NO_EM_RESTART': '1', 07:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:50 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:50 INFO - 'SHELL': '/bin/bash', 07:46:50 INFO - 'SHLVL': '1', 07:46:50 INFO - 'TERM': 'linux', 07:46:50 INFO - 'TMOUT': '86400', 07:46:50 INFO - 'USER': 'cltbld', 07:46:50 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:50 INFO - '_': '/tools/buildbot/bin/python'} 07:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:50 INFO - Running setup.py (path:/tmp/pip-rY02JW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:50 INFO - Running setup.py (path:/tmp/pip-MLMnhn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:50 INFO - Running setup.py (path:/tmp/pip-Xsm8Dg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:50 INFO - Running setup.py (path:/tmp/pip-Mh_KPF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:50 INFO - Running setup.py (path:/tmp/pip-E5PmOl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:50 INFO - Running setup.py (path:/tmp/pip-8FYH34-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:50 INFO - Running setup.py (path:/tmp/pip-HWA3b4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:51 INFO - Running setup.py (path:/tmp/pip-Mc8P3n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:51 INFO - Running setup.py (path:/tmp/pip-dNO_Jm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:51 INFO - Running setup.py (path:/tmp/pip-7od7Tj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:51 INFO - Running setup.py (path:/tmp/pip-QGb7xN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:51 INFO - Running setup.py (path:/tmp/pip-PQPC71-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:51 INFO - Running setup.py (path:/tmp/pip-VilIl9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:51 INFO - Running setup.py (path:/tmp/pip-BpYgAX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:51 INFO - Running setup.py (path:/tmp/pip-Eiaj7w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:51 INFO - Running setup.py (path:/tmp/pip-pdvQA5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:51 INFO - Running setup.py (path:/tmp/pip-Isk1jT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:46:51 INFO - Running setup.py install for manifestparser 07:46:51 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 07:46:51 INFO - Running setup.py install for mozcrash 07:46:51 INFO - Running setup.py install for mozdebug 07:46:52 INFO - Running setup.py install for mozdevice 07:46:52 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Running setup.py install for mozfile 07:46:52 INFO - Running setup.py install for mozhttpd 07:46:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Running setup.py install for mozinfo 07:46:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Running setup.py install for mozInstall 07:46:52 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Running setup.py install for mozleak 07:46:52 INFO - Running setup.py install for mozlog 07:46:52 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Running setup.py install for moznetwork 07:46:52 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 07:46:52 INFO - Running setup.py install for mozprocess 07:46:52 INFO - Running setup.py install for mozprofile 07:46:53 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 07:46:53 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 07:46:53 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 07:46:53 INFO - Running setup.py install for mozrunner 07:46:53 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 07:46:53 INFO - Running setup.py install for mozscreenshot 07:46:53 INFO - Running setup.py install for moztest 07:46:53 INFO - Running setup.py install for mozversion 07:46:53 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 07:46:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:46:53 INFO - Cleaning up... 07:46:53 INFO - Return code: 0 07:46:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:46:53 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')]} 07:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:53 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')]} 07:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:53 INFO - Using env: {'DISPLAY': ':0', 07:46:53 INFO - 'HOME': '/home/cltbld', 07:46:53 INFO - 'LANG': 'en_US.UTF-8', 07:46:53 INFO - 'LANGUAGE': 'en_US:en', 07:46:53 INFO - 'LOGNAME': 'cltbld', 07:46:53 INFO - 'MAIL': '/var/mail/cltbld', 07:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:53 INFO - 'MOZ_NO_REMOTE': '1', 07:46:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:53 INFO - 'NO_EM_RESTART': '1', 07:46:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:53 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:53 INFO - 'SHELL': '/bin/bash', 07:46:53 INFO - 'SHLVL': '1', 07:46:53 INFO - 'TERM': 'linux', 07:46:53 INFO - 'TMOUT': '86400', 07:46:53 INFO - 'USER': 'cltbld', 07:46:53 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:53 INFO - '_': '/tools/buildbot/bin/python'} 07:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:53 INFO - Running setup.py (path:/tmp/pip-HofWW4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:46:53 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)) 07:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:53 INFO - Running setup.py (path:/tmp/pip-lyaTNH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:46:53 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)) 07:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:53 INFO - Running setup.py (path:/tmp/pip-HevNBN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:46:53 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)) 07:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:53 INFO - Running setup.py (path:/tmp/pip-Pw0Ddf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:46:53 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)) 07:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:54 INFO - Running setup.py (path:/tmp/pip-9M73QX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:54 INFO - Running setup.py (path:/tmp/pip-vw8L3J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:54 INFO - Running setup.py (path:/tmp/pip-TjSt5v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:54 INFO - Running setup.py (path:/tmp/pip-G5QJ9u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:54 INFO - Running setup.py (path:/tmp/pip-2BVOzk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:54 INFO - Running setup.py (path:/tmp/pip-m_xr5e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:54 INFO - Running setup.py (path:/tmp/pip-1UZgTt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:54 INFO - Running setup.py (path:/tmp/pip-i1_cNd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:54 INFO - Running setup.py (path:/tmp/pip-2YLJ66-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:54 INFO - Running setup.py (path:/tmp/pip-Nj2gwv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:54 INFO - Running setup.py (path:/tmp/pip-okCFlF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:54 INFO - Running setup.py (path:/tmp/pip-H91JJJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:46:54 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)) 07:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:54 INFO - Running setup.py (path:/tmp/pip-gP1_cv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:46:54 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)) 07:46:54 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)) 07:46:54 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)) 07:46:54 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)) 07:46:54 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)) 07:46:54 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)) 07:46:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:56 INFO - Downloading blessings-1.6.tar.gz 07:46:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 07:46:56 INFO - Installing collected packages: blessings 07:46:56 INFO - Running setup.py install for blessings 07:46:56 INFO - Successfully installed blessings 07:46:56 INFO - Cleaning up... 07:46:56 INFO - Return code: 0 07:46:56 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 07:46:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:46:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:46:57 INFO - Reading from file tmpfile_stdout 07:46:57 INFO - Current package versions: 07:46:57 INFO - argparse == 1.2.1 07:46:57 INFO - blessings == 1.6 07:46:57 INFO - blobuploader == 1.2.4 07:46:57 INFO - docopt == 0.6.1 07:46:57 INFO - manifestparser == 1.1 07:46:57 INFO - mozInstall == 1.12 07:46:57 INFO - mozcrash == 0.16 07:46:57 INFO - mozdebug == 0.1 07:46:57 INFO - mozdevice == 0.47 07:46:57 INFO - mozfile == 1.2 07:46:57 INFO - mozhttpd == 0.7 07:46:57 INFO - mozinfo == 0.9 07:46:57 INFO - mozleak == 0.1 07:46:57 INFO - mozlog == 3.1 07:46:57 INFO - moznetwork == 0.27 07:46:57 INFO - mozprocess == 0.22 07:46:57 INFO - mozprofile == 0.27 07:46:57 INFO - mozrunner == 6.11 07:46:57 INFO - mozscreenshot == 0.1 07:46:57 INFO - mozsystemmonitor == 0.0 07:46:57 INFO - moztest == 0.7 07:46:57 INFO - mozversion == 1.4 07:46:57 INFO - psutil == 3.1.1 07:46:57 INFO - requests == 1.2.3 07:46:57 INFO - wsgiref == 0.1.2 07:46:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:57 INFO - Using env: {'DISPLAY': ':0', 07:46:57 INFO - 'HOME': '/home/cltbld', 07:46:57 INFO - 'LANG': 'en_US.UTF-8', 07:46:57 INFO - 'LANGUAGE': 'en_US:en', 07:46:57 INFO - 'LOGNAME': 'cltbld', 07:46:57 INFO - 'MAIL': '/var/mail/cltbld', 07:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:57 INFO - 'MOZ_NO_REMOTE': '1', 07:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:57 INFO - 'NO_EM_RESTART': '1', 07:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:57 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:57 INFO - 'SHELL': '/bin/bash', 07:46:57 INFO - 'SHLVL': '1', 07:46:57 INFO - 'TERM': 'linux', 07:46:57 INFO - 'TMOUT': '86400', 07:46:57 INFO - 'USER': 'cltbld', 07:46:57 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:57 INFO - '_': '/tools/buildbot/bin/python'} 07:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 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)) 07:46:57 INFO - Cleaning up... 07:46:57 INFO - Return code: 0 07:46:57 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 07:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:57 INFO - Using env: {'DISPLAY': ':0', 07:46:57 INFO - 'HOME': '/home/cltbld', 07:46:57 INFO - 'LANG': 'en_US.UTF-8', 07:46:57 INFO - 'LANGUAGE': 'en_US:en', 07:46:57 INFO - 'LOGNAME': 'cltbld', 07:46:57 INFO - 'MAIL': '/var/mail/cltbld', 07:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:57 INFO - 'MOZ_NO_REMOTE': '1', 07:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:57 INFO - 'NO_EM_RESTART': '1', 07:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:57 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:57 INFO - 'SHELL': '/bin/bash', 07:46:57 INFO - 'SHLVL': '1', 07:46:57 INFO - 'TERM': 'linux', 07:46:57 INFO - 'TMOUT': '86400', 07:46:57 INFO - 'USER': 'cltbld', 07:46:57 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:57 INFO - '_': '/tools/buildbot/bin/python'} 07:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:57 INFO - Downloading/unpacking jsonschema 07:46:57 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 07:46:57 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 07:46:57 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 07:46:57 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 07:46:57 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 07:46:57 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 07:46:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:46:59 INFO - Downloading/unpacking functools32 (from jsonschema) 07:46:59 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 07:46:59 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 07:46:59 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 07:46:59 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 07:46:59 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 07:46:59 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 07:46:59 INFO - Downloading functools32-3.2.3-2.tar.gz 07:46:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 07:46:59 INFO - warning: no files found matching '*.txt' 07:46:59 INFO - no previously-included directories found matching 'build' 07:46:59 INFO - no previously-included directories found matching 'dist' 07:46:59 INFO - no previously-included directories found matching '.git*' 07:46:59 INFO - Installing collected packages: jsonschema, functools32 07:46:59 INFO - Running setup.py install for functools32 07:46:59 INFO - warning: no files found matching '*.txt' 07:46:59 INFO - no previously-included directories found matching 'build' 07:46:59 INFO - no previously-included directories found matching 'dist' 07:46:59 INFO - no previously-included directories found matching '.git*' 07:46:59 INFO - Successfully installed jsonschema functools32 07:46:59 INFO - Cleaning up... 07:46:59 INFO - Return code: 0 07:46:59 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 07:46: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')]} 07:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46: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')]} 07:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fabf040ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', '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 07:46:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 07:46:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 07:46:59 INFO - Using env: {'DISPLAY': ':0', 07:46:59 INFO - 'HOME': '/home/cltbld', 07:46:59 INFO - 'LANG': 'en_US.UTF-8', 07:46:59 INFO - 'LANGUAGE': 'en_US:en', 07:46:59 INFO - 'LOGNAME': 'cltbld', 07:46:59 INFO - 'MAIL': '/var/mail/cltbld', 07:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:59 INFO - 'MOZ_NO_REMOTE': '1', 07:46:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:46:59 INFO - 'NO_EM_RESTART': '1', 07:46:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:46:59 INFO - 'PWD': '/builds/slave/test-pgo', 07:46:59 INFO - 'SHELL': '/bin/bash', 07:46:59 INFO - 'SHLVL': '1', 07:46:59 INFO - 'TERM': 'linux', 07:46:59 INFO - 'TMOUT': '86400', 07:46:59 INFO - 'USER': 'cltbld', 07:46:59 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:59 INFO - '_': '/tools/buildbot/bin/python'} 07:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:00 INFO - Downloading/unpacking flake8 07:47: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 07:47: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 07:47: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 07:47: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 07:47: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 07:47: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 07:47:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:47:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:47:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 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 07:47:01 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 07:47:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:47: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 07:47: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 07:47: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 07:47: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 07:47: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 07:47: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 07:47:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 07:47:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:47:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:47:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:47:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:47:02 INFO - no previously-included directories found matching 'docs/_build' 07:47:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:47:02 INFO - Running setup.py install for pep8 07:47:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:47:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:47:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:47:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:47:02 INFO - no previously-included directories found matching 'docs/_build' 07:47:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 07:47:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:47:02 INFO - Cleaning up... 07:47:02 INFO - Return code: 0 07:47:02 INFO - Running post-action listener: _resource_record_post_action 07:47:02 INFO - Running post-action listener: _start_resource_monitoring 07:47:02 INFO - Starting resource monitoring. 07:47:02 INFO - ##### 07:47:02 INFO - ##### Running install step. 07:47:02 INFO - ##### 07:47:02 INFO - Running pre-action listener: _resource_record_pre_action 07:47:02 INFO - Running main action method: install 07:47:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:47:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:47:02 INFO - Reading from file tmpfile_stdout 07:47:02 INFO - Detecting whether we're running mozinstall >=1.0... 07:47:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 07:47:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 07:47:02 INFO - Reading from file tmpfile_stdout 07:47:02 INFO - Output received: 07:47:02 INFO - Usage: mozinstall [options] installer 07:47:02 INFO - Options: 07:47:02 INFO - -h, --help show this help message and exit 07:47:02 INFO - -d DEST, --destination=DEST 07:47:02 INFO - Directory to install application into. [default: 07:47:02 INFO - "/builds/slave/test-pgo"] 07:47:02 INFO - --app=APP Application being installed. [default: firefox] 07:47:02 INFO - mkdir: /builds/slave/test-pgo/build/application 07:47:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 07:47:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 07:47:17 INFO - Reading from file tmpfile_stdout 07:47:17 INFO - Output received: 07:47:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 07:47:17 INFO - Running post-action listener: _resource_record_post_action 07:47:17 INFO - ##### 07:47:17 INFO - ##### Running run-tests step. 07:47:17 INFO - ##### 07:47:17 INFO - Running pre-action listener: _resource_record_pre_action 07:47:17 INFO - Running main action method: run_tests 07:47:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 07:47:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 07:47:17 INFO - Python 2.7.3 07:47:17 INFO - Return code: 0 07:47:17 INFO - grabbing minidump binary from tooltool 07:47: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')]} 07:47:17 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 0x2445350>, '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 0x2446250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24466e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 07:47:17 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 07:47:17 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 07:47:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:47:17 INFO - Return code: 0 07:47:17 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 07:47:17 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 07:47:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 07:47:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 07:47:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 07:47:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 07:47:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 07:47:18 INFO - Return code: 0 07:47:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 07:47:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 07:47:18 INFO - Using env: {'DISPLAY': ':0', 07:47:18 INFO - 'HOME': '/home/cltbld', 07:47:18 INFO - 'LANG': 'en_US.UTF-8', 07:47:18 INFO - 'LANGUAGE': 'en_US:en', 07:47:18 INFO - 'LOGNAME': 'cltbld', 07:47:18 INFO - 'MAIL': '/var/mail/cltbld', 07:47:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:47:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 07:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:18 INFO - 'MOZ_NO_REMOTE': '1', 07:47:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:47:18 INFO - 'NO_EM_RESTART': '1', 07:47:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:47:18 INFO - 'PWD': '/builds/slave/test-pgo', 07:47:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 07:47:18 INFO - 'SHELL': '/bin/bash', 07:47:18 INFO - 'SHLVL': '1', 07:47:18 INFO - 'TERM': 'linux', 07:47:18 INFO - 'TMOUT': '86400', 07:47:18 INFO - 'USER': 'cltbld', 07:47:18 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257', 07:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:18 INFO - '_': '/tools/buildbot/bin/python'} 07:47:18 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 07:47:18 INFO - mozversion INFO | application_buildid: 20160121051922 07:47:18 INFO - mozversion INFO | application_changeset: 57a4738271792310f2b061fee8447060b13e961d 07:47:18 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 07:47:18 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:47:18 INFO - mozversion INFO | application_name: Firefox 07:47:18 INFO - mozversion INFO | application_remotingname: firefox-dev 07:47:18 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:47:18 INFO - mozversion INFO | application_vendor: Mozilla 07:47:18 INFO - mozversion INFO | application_version: 45.0a2 07:47:18 INFO - mozversion INFO | platform_buildid: 20160121051922 07:47:18 INFO - mozversion INFO | platform_changeset: 57a4738271792310f2b061fee8447060b13e961d 07:47:18 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:47:18 INFO - mozversion INFO | platform_version: 45.0a2 07:47:18 INFO - 2016-01-21 07:47:18,918 DEBUG : using testdate: 1453391238 07:47:18 INFO - 2016-01-21 07:47:18,918 DEBUG : actual date: 1453391238 07:47:18 INFO - 2016-01-21 07:47:18,926 INFO : starting webserver on 'localhost:48981' 07:47:18 INFO - 2016-01-21 07:47:18,927 INFO : Starting test suite talos-linux64-ix-046 07:47:18 INFO - 2016-01-21 07:47:18,928 INFO : Starting test dromaeo_css 07:47:18 INFO - 2016-01-21 07:47:18,928 DEBUG : operating with platform_type : linux_ 07:47:18 INFO - 2016-01-21 07:47:18,929 INFO : Initialising browser for dromaeo_css test... 07:47:18 INFO - 2016-01-21 07:47:18,940 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHzeMvU/profile http://localhost:48981/getInfo.html 07:47:23 INFO - 2016-01-21 07:47:23,101 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:47:23 INFO - 2016-01-21 07:47:23,102 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:47:23 INFO - 2016-01-21 07:47:23,102 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:47:23 INFO - 2016-01-21 07:47:23,102 DEBUG : BROWSER_OUTPUT: __metrics 07:47:23 INFO - 2016-01-21 07:47:23,238 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:47:24 INFO - 2016-01-21 07:47:24,009 INFO : Browser initialized. 07:47:24 INFO - 2016-01-21 07:47:24,010 INFO : Running cycle 1/1 for dromaeo_css test... 07:47:24 INFO - 2016-01-21 07:47:24,010 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHzeMvU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:47:35 INFO - 2016-01-21 07:47:35,871 DEBUG : BROWSER_OUTPUT: RSS: Main: 137900032 07:47:35 INFO - 2016-01-21 07:47:35,872 DEBUG : BROWSER_OUTPUT: 07:48:26 INFO - 2016-01-21 07:48:26,706 DEBUG : BROWSER_OUTPUT: 1453391306705 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] 07:50:35 INFO - 2016-01-21 07:50:35,233 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48981/tests/dromaeo/cssquery-ext.html) 07:50:36 INFO - 2016-01-21 07:50:36,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 240152576 07:50:36 INFO - 2016-01-21 07:50:36,323 DEBUG : BROWSER_OUTPUT: 07:53:38 INFO - 2016-01-21 07:53:38,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/dromaeo/cssquery-ext.html (next: http://localhost:48981/tests/dromaeo/cssquery-jquery.html) 07:53:39 INFO - 2016-01-21 07:53:39,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 214708224 07:53:39 INFO - 2016-01-21 07:53:39,210 DEBUG : BROWSER_OUTPUT: 07:56:40 INFO - 2016-01-21 07:56:40,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48981/tests/dromaeo/cssquery-mootools.html) 07:56:41 INFO - 2016-01-21 07:56:41,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 204144640 07:56:41 INFO - 2016-01-21 07:56:41,507 DEBUG : BROWSER_OUTPUT: 07:59:43 INFO - 2016-01-21 07:59:43,267 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48981/tests/dromaeo/cssquery-prototype.html) 07:59:43 INFO - 2016-01-21 07:59:43,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 199286784 07:59:43 INFO - 2016-01-21 07:59:43,835 DEBUG : BROWSER_OUTPUT: 08:02:46 INFO - 2016-01-21 08:02:46,194 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48981/tests/dromaeo/cssquery-yui.html) 08:02:46 INFO - 2016-01-21 08:02:46,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 203960320 08:02:46 INFO - 2016-01-21 08:02:46,764 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,078 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/dromaeo/cssquery-yui.html (next: http://localhost:48981/tests/dromaeo/cssquery-dojo.html) 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: RSS: Main: 224096256 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3186.522021696745;3219.597550306212;3251.528528363389;3254.2677897473072;3267.6568960259174;5388.434214407484;5414.377346605142;5421.268128802611;5427.592930530206;5444.128939369702;5462.836114916549;5465.617406781527;5518.448155184479;5520.475554822286;5525.4474552544725;4918.877028074291;5011.574016208618;5011.599072074244;5057.544820966113;5090.160123579623;4949.653524253304;5041.873953151164;5120.92318615221;5121.820736274231;5122.129238029544;4961.652684312099;5047.823326183584;5049.67177133487;5078.64449488536;5082.745862706868;5106.412762532319;5159.277701121846;5169.638125331228;5175.896482070338;5195.948040519603;4850.708957462563;4876;4951.603871690292;5059.924101138486;5061.468545802712;5415.53967912724;5450.31871016123;5481.698506582136;5482.095454250044;5483.8080667176455;5976.402359763989;5984.581079324449;6006.789762358297;6031.24609423822;6034.155218269446;5811.912821307684;5838.41615838417;5897.6166549174595;5917.171595976523;6064.151018857364;5460.945390546083;5461.371942226655;5512.2558454608115;5524.419935906712;5584.91622625661;5964.761409543657;5967.701614919237;5973.074173503114;5978.163057172;5986.610870293417;5678.801241956512;5788.88422231553;5790.826275211666;5877.794277200277;5920.703964801743;5965.492933100648;5966.671833049224;5980.700964951735;5983.222181116427;5986.341502434729;5711.6858572778065;5893.44012318829;5938.703064846741;5956.017257152608;5956.076808094752;5783.942160578425;5788.942110578924;5858.53131749459;5858.912116318259;5872.383399743051;4555.06621142665;4577.496475387705;4593.265077587636;4593.747343896051;4623.606993405531;3984.163325701635;4058.127502586958;4084.7753373564738;4103.076807718239;4130.215259100762;5498.120300751861;5541.695206763586;5564.554835613141;5567.192757050205;5585.357683866326;5978.3124940631515;6021.307549631849;6021.879562408727;6026.5178785697035;6061.181740465069;5410.567154627621;5438.320209973754;5522.061249587579;5696.145578163226;5736.569757268222;5175.275461435402;5188.688678679291;5190.351206099238;5192.039472697563;5198.740062996835;6112.969435152885;6113.510919126459;6119.5104391648565;6126.23422072241;6126.448619624255;5659.943400566106;5772.56705747079;5866.67733274674;6001.939980600313;6029.969850150721;6107.603005804608;6120.44915957566;6123.08153776937;6123.7550497981365;6130.478909292582;5878.588498805043;5917.437843404869;5923.289205295392;5928.644281343133;5930.7627694893445;5530.253415788817;5684.090545512699;5889.057750760029;5980.282366116094;6014.458698717136;5825.854353641193;5839.620424672383;5840.766369345179;5843.29880414353;5845.357010728731;5840.532757379315;5856.502197313276;5858.091995740667;5861.853453663522;5863.237779088691;5610.13042978339;5682.715864206856;5710.17202505626;5781.595288329777;5801.709914504342;5902.852428689317;5903.881922361443;5904.793332233351;5906.40935906406;5906.557008224315;5718.342390625131;5767.5674324425;5772.451617096369;5772.740226689725;5787.189793428839 08:05:49 INFO - 2016-01-21 08:05:49,345 DEBUG : BROWSER_OUTPUT: |1;ext.html;572.9054705973512;614.2352770800353;622.4087117238624;622.7104396455649;623.8689875126223;30483.237919052033;37986.62013379866;38343.42484862729;38395;38430.80784596073;44990.55009449904;45726.314105288446;45858.770706146424;45975.54024459763;46097.539024609745;8009.839803203932;8120.878186827203;8135.308498777611;8952.731418057469;9130.26957843371;10839.13286937043;11035;11047.447627618587;11047.558097676083;11050.723731906726;10811;10887.83668244977;10932.398718070503;10933.56265749369;10953.397563134024;8269;8450.661973521052;10777.892221077846;10798.190135739811;11003.834942475869;10528.789424211549;10674;10675.306105103222;10679.41263230522;10683.893161068367;42565.78717106426;42587.78706106481;42782;42999.78500107479;43003.569964300266;3609.205974685565;3691.354013047706;3706.8517259309865;3732.5520937487404;3777.7166712496673;30045.248868778235;30287.69712302871;30513.084607461813;30689.69310306891;31199.532007019912;4255.510616279136;4284.357346398034;4339.435873336478;4345.521992580813;4358.36803663467;278219;282037.99999999796;282157;282849;283678;58955.11567326493;60240.397596024355;65862.67068664626;66188;72885.6355718218;66194.66902665456;66323;66398.33601663921;66874.66562667253;66967.66516167388;14233.28833558318;14403;14435.639109022359;14499.565013049414;14535.563933082025;11264.098872090213;11321.320720756781;11337.376444295478;11433.714157146138;11440.427978601037;22305.330840074825;22439.32682019542;22702.205422810122;22718.886405567868;22757.772422275895;8524.40329176964;8585.699500517578;8629.913700862911;8785.956070219736;8814.471131732116;992.4194346307347;1028.6554004408606;1035.316690983947;1102.4542851288663;1118.9608363707393;29221;29459.410811784073;30177;30202.848985754932;30484.69515304863;1073.897979691928;1086.9565217391216;1087.983680244797;1107.9390633515234;1182.2374568403332;14507;14905.180215088058;15016.774748378783;16701.41545045942;17460.30158793634;414983;420739;422064;422209;422580;57554;57945.71027144837;58045.709771451715;58051.12923306154;58055.419445805004;1327.6216278360814;1378.4830688491816;1435.9856401435852;1467.8404060792068;1477.364733164728;29736;29939.251518712208;33319;33972.83013584916;34725.47911781376;44887.77556112198;45567.31649025201;54215.18677219766;54722.45277547333;55015.44984550103;306183;309145;310689;311057;311859;44256;44810;45145.32282015707;45192.774036130926;45765.542344576126;48447.5155248443;48790.75604621954;49296.50703492919;49780.502194977584;50152.24771628356;40801;41698.58301416947;41946.37080443735;41979;42902.14195716131;40680;41508.79245603873;41603.37594936139;41894.790526047174;42282;40313.59686403099;40479;40490.79754601208;40936;44047.77976110099;31895.521567176045;32179.51730724088;34087.318253634294;34325;34823.12942176466;26844.463110737284;26947.595786062833;27011.729882701708;27174.456510870066;27213.86393068022 08:05:49 INFO - 2016-01-21 08:05:49,346 DEBUG : BROWSER_OUTPUT: |2;jquery.html;388.4251308064065;390.05995550722764;391.17071807767536;395.1366264711607;396.73815281913943;14065.367058482367;17582;20004;20010.29963951262;20535.897320513377;18183.636327273445;18191.636167276647;18556.814431855677;18609.813901860976;18618.34835780748;6095.573309868311;6203.596766210205;6204.224471941007;6208.6585237811905;6211.47202487789;6116.143739876398;6278.780242691507;6312.242530896299;6325.304216536178;6326.620402775849;4517.932231016537;4642.303654451825;6285.654289014125;6289.716962736665;6299.622022678631;6757.121574195372;6987.82530436738;6995.755148569776;7041.225465198824;7043.225245222996;6624.867502649969;6789.25318214996;6793.218779840332;6794.252632210453;6795.320467953215;22180.334589962327;22504.662430063563;22641.77358226413;23165.884170579207;23683.763162368326;2527.2670925431603;2542.605896086091;2577.703564090135;2634.2624065261757;2698.3119304577403;14781.33483993223;15153.772693409608;15245.085294882232;15538.834587405876;15602.141882196474;2151.171798857451;2209.823214142865;2223.7998580127864;2238.507528343762;2288.1305104060516;66892;72318.63840680763;72359;73620;74050.62974685145;16426.260818262966;16474.093924834247;16499.257533411026;16549.751753723704;16621.83378166227;25868.22395328143;25932.481350372887;26060.739392605832;26145.869270653904;26236;9341.252699784136;9376;9429.339946203838;9489.38319009276;9501.667441639509;7648.541087534766;7657.693692252247;7669.463137580316;7670.731524396731;7728.18854020331;8024.879626805603;8117.675292988214;8121.959390203129;8175.63209655567;8198.42611017235;1995.5410255640993;2314.2710046335687;3098.721115099652;3166.762492813048;3171.349873276019;906.7053207707521;955.6082006377352;958.3195930889008;1017.6387382479261;1039.750459889621;17134.657306854042;17373.82626173722;17512;17838.19728112212;17948.192331345123;477.45093142885264;482.9251466022772;492.8496808473378;516.5738265930622;1039.6257347354947;6190.473809726223;6256.937430625727;6267.435930766253;6290.119383286344;6337.9366206337345;393092;396034;600761;689563;697374;87466.56266718626;89219;91307.54346228226;91412.54293728489;91747;506.9062223488593;518.4633903909511;518.8832512684692;519.451978163034;519.6881870877444;10810.243282970117;10833.945830270799;10916.290441121302;10978.725531861448;10981.505832237408;37178;37537.2492550153;37913;38651.32812382819;38890.6110938887;46665.3000205004;47999.280010799164;49060;49705;52143;10251.94874025655;10433.113185379329;10522.421266830397;10569.101626361846;10586.364818110938;12375.69060773488;14563.417463301774;17512.299508019536;17703;18093.728594071363;14550.781738273723;14718.411263549338;14767.92616036913;14848.257587120817;14939.626509337353;14506.564803055926;14656;14723.779143312644;15133.697326053638;15215.847841521443;14973.700525989638;14990.325435355653;15277.6180595486;15413;15496.535103946899;9648.276379271723;10135.189184865341;10423.166146708396;10426.165906727596;10439.112675422673;10571.788564228518;10618.469076545987;13991.510297139552;14489.347979340744;15239.3904243829 08:05:49 INFO - 2016-01-21 08:05:49,348 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1520.2474774986383;1523.5353217268735;1603.5269595469335;1803.9458816235513;1828.5337239004052;7022.192447868497;7597.164311925684;7860.0960889497655;7867.2919437250775;7917.485363451386;7765.563370776396;7999.120096789363;8008.479448835822;8011.158828323015;8016.358691304697;1974.9308774192907;2020.6867935469952;2215.601191785478;2260.570491606598;2292.128990983424;2297.8621282723007;2299.4711216420233;2307.1232931486097;2308.849924754894;2309.3533810532995;2186.0599942024924;2270.795628393444;2271.7273927128767;2272.8295377846644;2274.6815445837597;2252.076648574085;2269.319204238738;2271.375371772759;2274.9090036398525;2275.442516583431;2282.589133955887;2288.5651726171973;2315.23597212921;2317.8725170115636;2320.048780000209;7998.440109192359;8064.758057258292;8074.4347895647325;8077.919220807776;8079.878801817978;4779.784909679072;4846.151923413388;4877.8780530486665;4878.829240976549;4887.315775791392;3098.643655979546;3123.375324935022;3152.763542734281;3190.441672707267;3211.3577284542953;1017.7557386227326;1034.2398337222135;1044.4777611194402;1066.248294952057;1078.4661592511757;11186.384748838811;11203;11222.438878056064;11223.102151827752;11230.94384528072;4295.334223195409;4304.5910638489295;4369.584889435499;4371.934420983688;4390.209762242827;9276.62893484253;9281.72154835356;9288.164065234027;9292.814143717223;9309.115634014757;4881.560659540587;4897.8285759998225;4899.2896030075435;4902.362692849907;4904.43598986114;4449.888752781141;4470.307102399133;4481.887952801141;4487.259602165672;4487.753173575485;3515.8945231643092;3544.0962554548423;3555.6444355564754;3604.5133906922756;3781.470594116826;1222.2849632964637;1226.1478272600634;1227.0429065328879;1229.250157403983;1247.2516490106045;1101.2291396022815;1105.3423213188141;1112.7385064509833;1295.831541899566;1388.965275868091;7421.1094668639025;7458.962705186439;7486;7522.849543009219;7543.207963163899;1065.2809353686387;1078.3475997021847;1110.5613282753268;1116.1659698934186;1147.8909503597145;5915.260592425947;5967.850803729942;5976.342602313742;5980;5984.940150598438;9171;9291.76770580741;9404.482753448627;9524.428534287965;9537.380070295409;9246.4914429707;9288.442693438416;9401.388909720847;9411.952940235255;9420.576074076684;7270.927290727025;7314.817129571803;7342.265773422721;7381.88927166093;7401.740939067106;6964.721411143498;6989.405900498515;6998.720051197895;7002.404795592432;7003.26465721092;8231.670733170846;8246;8378.664853405795;8423.07346191906;8423.115572865006;8112.716054938049;8224.465409748347;8314.376421768271;8364.832703346021;8377.162283771575;6746.325367463214;6975.825604359931;6979.511434199558;7023.438124950094;7036.366726994595;6967.512274140964;7092.929070709227;7427.925720742724;7716.5755883426955;7720.420968427503;7246.232754164786;7351.926480735338;7359.852802944019;7406.037215161994;7530.209328020479;7200.027996220443;7210.89183662235;7280;7329.670164842488;7398.334149926317;6881.277465866212;6908.48186386013;6933.306669333027;7176.677049532679;7180.102487189101;6690.431313338421;6734.02356658272;6747;6749.190097188369;6759.087523184504;6855.383015528427;6870.519063665495;6896.103506544117;6905.93094069073;6976.790696279119 08:05:49 INFO - 2016-01-21 08:05:49,348 DEBUG : BROWSER_OUTPUT: |4;prototype.html;364.4951741837555;365.84634453529515;371.7787916189866;372.6404020120583;378.5325123472511;6161.630302181867;6174.413430724086;6177.351378105302;6187.133801267821;6233.812985610428;6120.602160859541;6129.387061293868;6132.509399248061;6145.108959200913;6148.5388595855375;4730.293358108686;4750.691205071659;4786.904261914759;4800.3279540864305;4818.156822556056;4789.401324834395;4800.375951126349;4806.399200099988;4808.735519546423;4813.350197723315;4472.955270447286;4552.226121559326;4748.5726284634375;4750.8337208197545;4758.214894542396;4643.86068417948;4724.787384567703;4753.524647535253;4763.76181190939;4764.404449443819;4181.414601955728;4197.538270790211;4205.369194620831;4211.578842115795;4219.2194444027955;5630.40880707528;5703.971480142614;5783;5862.589618726691;5918.733656985403;5507.972460137714;5511.283533140706;5514.641548299388;5519.365272993617;5520.558355331604;6487.83780405489;6524.314946930552;6537.901931471032;6538.084668146464;6540.967295163541;4551.362809206714;4634.721916684976;4642.025174713314;4658.208104622222;4675.088357770236;6772.525923185379;6774.0854984576945;6785.050092986986;6785.287544807823;6791.932080679179;4743.075100355398;4792.856214313576;4798.808047678054;4798.976005119952;4799.952000480021;4856;4858.05267972749;4859.927101093486;4862.079478606001;4863.537963893425;5375.81184658535;5477.315335583052;5477.849651573135;5493.478119578634;5528.004959107318;6566.146400967844;6569.967150164219;6576.816173088794;6580.21037475506;6580.967095164494;1716.8969861808332;1733.1767410479922;1735.739639054152;1738.2959901240017;1741.0685283373334;1188.1267268557604;1197.8442802435802;1199.0707201918583;1200.099925056208;1228.956986505468;6379.489640828722;6394.360563943568;6396.744130234832;6402.167718196698;6403.48772098231;6769.356911093537;6773.695183716744;6780.796576102724;6783.660816959231;6785.59286442815;6297.370262973666;6355.809325720235;6403.199600049994;6406.871862562816;6408.070829729756;3649.0694872807794;3662.066173125836;3682.226732386175;3683.834227459771;3684.3552378333684;6614.768483103068;6795.966020169966;6886.586804791628;6894.24163342032;6895.655217239118;6716.966415167893;6896.379325860697;6935.306469353024;6962.7911162665205;7000.824979375557;6976.197737260179;6978.127734033246;6981.05755722981;6983.441324694114;6985.022096906438;6725.596464212163;6822.317768223138;6826.522143449911;6842.452603791784;6844.657767111525;6719.193696756421;6725.764598239038;6727;6741.696623651849;6745.291744366771;6927.549709268881;6933.618650974245;6943.861122777617;6948.756793512202;6990.09128813251;4785.641076919175;4844.370231869975;4853.902921941471;4862.781174847211;4870.342503761947;6804.353586409283;6806.42145417645;6806.693698783665;6815.216250131299;6826.317368263134;6649.867002659823;6655.800325990228;6663.566868153554;6666.500012498985;6669.199696036312;6531.183602049744;6640.734370625121;6649.401553860176;6649.833754156185;6660;6546.607203567801;6548.116004339353;6554.115194448879;6566.343365663395;6567.573107747981;6657.600543967377;6659.700313485808;6665.400113989765;6669.233038200669;6669.933300666931;6357.777477788255;6494.512911531559;6502.544821862424;6502.934970650422;6514.804555863332 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: |5;yui.html;763.6678045050403;770.7495064104164;894.4856707393249;900.671254991928;907.7276816954912;14340.928295358523;14603.269836508172;14628.049176803514;14634.780478292834;14666.486672966448;13756.93121534391;13832.585022449342;13935.790963135561;13954.651133721636;13970;756.4288961833807;766.3562607409775;864.299917067176;896.6144557840158;919.9402038867486;915.1169121797465;927.6845872403381;929.2380248196481;929.9395539289958;930.483581612205;943.9197668198211;954.1269738189549;961.4856052012175;962.5572236771059;962.9711108666751;801.4710291207804;801.7795106345743;941.8210539997378;946.1957336264155;953.132649289147;704.9541779784349;707.2432497227968;707.7027648387638;708.875946709329;709.5529816215802;13588.116772409761;13743.381547830271;13747.793783093262;13756.243406612632;13763.174209547458;12359.93820030903;12548;12683.36583170847;13000.674983125404;13038.869611303955;10610.840837387444;10643.946780266126;10824.945875270574;10877.347359158397;11419.942900285445;3704.5369328833895;3835.9808200959096;3854.807259637007;3888.2806680764147;3907.648311651965;49048.50951490475;49397;49542.256866147036;49788.50211497875;50077.49922500765;10992;11086.501107450023;11097.72255693598;11476.88523114775;11644.126690498246;19049.42851714423;19200.80799192018;19204.327848525234;19241;19264.133114009903;7755.689772409153;7802.882956755653;8085.393595480363;8135.633896474673;8193.549354785544;4289.978550107229;4540.909181816345;4573.176828170961;4574.222382195035;4652.325412815123;3180.061881744891;3189.2505261263586;3194.6006749156354;3200;3205.583274174389;565.6916980245776;581.7527550790982;582.909649004405;592.7954855574819;597.1013624495107;629.3894921925724;707.3598393453932;716.3660160757663;737.3584981066507;748.7940816275568;8704.564771761387;8941.687040953535;9012.098790121066;9095.818083638422;9162.908370916206;595.214317101422;697.7243988624465;699.618707804248;729.234303980818;763.2520130272364;3833.3674943633987;3889.2804831106337;3910.687145028391;4020.2361551305744;4021.678265738734;58171;58338.12492812611;58587.414125859046;59096.70451647714;60158.398416016156;48635;49650.75174624104;49875.75062124666;49930.251046234334;50126.498735012916;44851.757412129264;45037.77481112639;45298.320525192146;46497.30254046192;46839.765801170775;9101.180893719596;9174.770630734285;9249.537523123952;9362.906370936204;9493.857592135986;36528.817355914114;37261.254774903806;37698;38005.619943801314;38148.61851381451;46730;46930.296045560026;46977;47479.52520474751;47777;11811.173217874666;11862.703432414259;11990.640280791591;12054.457549410474;12376.690582735504;12910.160839545399;13003.154794938298;13023.153495022792;13155.934220328838;13431.395587198795;12301;12631.93684031574;12889.355532223539;13270.535531256359;13382.330883455594;11446.313221206754;11461.828072578752;11611.767764644828;11914.642560723192;11953.462094205608;11990.220635658654;12134.635960921187;12198.14612977083;12278.324692142018;12769.616911492298;11037.503312351124;11065.391403472886;11124.944375278072;11215.214934954474;11273.943630281796;9296.07039296065;10109.14072303833;10121.342112762646;10214.846777298495;10632.574697012033 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,846 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: __startTimestamp1453392349343__endTimestamp 08:05:49 INFO - 2016-01-21 08:05:49,349 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5505.60 Median:5718.34 stddev:616.68 (10.8%) stddev-sans-first:591.13 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: Values: 3186.5 3219.6 3251.5 3254.3 3267.7 5388.4 5414.4 5421.3 5427.6 5444.1 5462.8 5465.6 5518.4 5520.5 5525.4 4918.9 5011.6 5011.6 5057.5 5090.2 4949.7 5041.9 5120.9 5121.8 5122.1 4961.7 5047.8 5049.7 5078.6 5082.7 5106.4 5159.3 5169.6 5175.9 5195.9 4850.7 4876.0 4951.6 5059.9 5061.5 5415.5 5450.3 5481.7 5482.1 5483.8 5976.4 5984.6 6006.8 6031.2 6034.2 5811.9 5838.4 5897.6 5917.2 6064.2 5460.9 5461.4 5512.3 5524.4 5584.9 5964.8 5967.7 5973.1 5978.2 5986.6 5678.8 5788.9 5790.8 5877.8 5920.7 5965.5 5966.7 5980.7 5983.2 5986.3 5711.7 5893.4 5938.7 5956.0 5956.1 5783.9 5788.9 5858.5 5858.9 5872.4 4555.1 4577.5 4593.3 4593.7 4623.6 3984.2 4058.1 4084.8 4103.1 4130.2 5498.1 5541.7 5564.6 5567.2 5585.4 5978.3 6021.3 6021.9 6026.5 6061.2 5410.6 5438.3 5522.1 5696.1 5736.6 5175.3 5188.7 5190.4 5192.0 5198.7 6113.0 6113.5 6119.5 6126.2 6126.4 5659.9 5772.6 5866.7 6001.9 6030.0 6107.6 6120.4 6123.1 6123.8 6130.5 5878.6 5917.4 5923.3 5928.6 5930.8 5530.3 5684.1 5889.1 5980.3 6014.5 5825.9 5839.6 5840.8 5843.3 5845.4 5840.5 5856.5 5858.1 5861.9 5863.2 5610.1 5682.7 5710.2 5781.6 5801.7 5902.9 5903.9 5904.8 5906.4 5906.6 5718.3 5767.6 5772.5 5772.7 5787.2 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52380.83 Median:3719.70 stddev:89918.87 (2417.4%) stddev-sans-first:90086.98 08:05:49 INFO - 2016-01-21 08:05:49,350 DEBUG : BROWSER_OUTPUT: Values: 572.9 614.2 622.4 622.7 623.9 30483.2 37986.6 38343.4 38395.0 38430.8 44990.6 45726.3 45858.8 45975.5 46097.5 8009.8 8120.9 8135.3 8952.7 9130.3 10839.1 11035.0 11047.4 11047.6 11050.7 10811.0 10887.8 10932.4 10933.6 10953.4 8269.0 8450.7 10777.9 10798.2 11003.8 10528.8 10674.0 10675.3 10679.4 10683.9 42565.8 42587.8 42782.0 42999.8 43003.6 3609.2 3691.4 3706.9 3732.6 3777.7 30045.2 30287.7 30513.1 30689.7 31199.5 4255.5 4284.4 4339.4 4345.5 4358.4 278219.0 282038.0 282157.0 282849.0 283678.0 58955.1 60240.4 65862.7 66188.0 72885.6 66194.7 66323.0 66398.3 66874.7 66967.7 14233.3 14403.0 14435.6 14499.6 14535.6 11264.1 11321.3 11337.4 11433.7 11440.4 22305.3 22439.3 22702.2 22718.9 22757.8 8524.4 8585.7 8629.9 8786.0 8814.5 992.4 1028.7 1035.3 1102.5 1119.0 29221.0 29459.4 30177.0 30202.8 30484.7 1073.9 1087.0 1088.0 1107.9 1182.2 14507.0 14905.2 15016.8 16701.4 17460.3 414983.0 420739.0 422064.0 422209.0 422580.0 57554.0 57945.7 58045.7 58051.1 58055.4 1327.6 1378.5 1436.0 1467.8 1477.4 29736.0 29939.3 33319.0 33972.8 34725.5 44887.8 45567.3 54215.2 54722.5 55015.4 306183.0 309145.0 310689.0 311057.0 311859.0 44256.0 44810.0 45145.3 45192.8 45765.5 48447.5 48790.8 49296.5 49780.5 50152.2 40801.0 41698.6 41946.4 41979.0 42902.1 40680.0 41508.8 41603.4 41894.8 42282.0 40313.6 40479.0 40490.8 40936.0 44047.8 31895.5 32179.5 34087.3 34325.0 34823.1 26844.5 26947.6 27011.7 27174.5 27213.9 08:05:49 INFO - 2016-01-21 08:05:49,351 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,351 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31069.26 Median:3169.06 stddev:93632.94 (2954.6%) stddev-sans-first:93867.26 08:05:49 INFO - 2016-01-21 08:05:49,352 DEBUG : BROWSER_OUTPUT: Values: 388.4 390.1 391.2 395.1 396.7 14065.4 17582.0 20004.0 20010.3 20535.9 18183.6 18191.6 18556.8 18609.8 18618.3 6095.6 6203.6 6204.2 6208.7 6211.5 6116.1 6278.8 6312.2 6325.3 6326.6 4517.9 4642.3 6285.7 6289.7 6299.6 6757.1 6987.8 6995.8 7041.2 7043.2 6624.9 6789.3 6793.2 6794.3 6795.3 22180.3 22504.7 22641.8 23165.9 23683.8 2527.3 2542.6 2577.7 2634.3 2698.3 14781.3 15153.8 15245.1 15538.8 15602.1 2151.2 2209.8 2223.8 2238.5 2288.1 66892.0 72318.6 72359.0 73620.0 74050.6 16426.3 16474.1 16499.3 16549.8 16621.8 25868.2 25932.5 26060.7 26145.9 26236.0 9341.3 9376.0 9429.3 9489.4 9501.7 7648.5 7657.7 7669.5 7670.7 7728.2 8024.9 8117.7 8122.0 8175.6 8198.4 1995.5 2314.3 3098.7 3166.8 3171.3 906.7 955.6 958.3 1017.6 1039.8 17134.7 17373.8 17512.0 17838.2 17948.2 477.5 482.9 492.8 516.6 1039.6 6190.5 6256.9 6267.4 6290.1 6337.9 393092.0 396034.0 600761.0 689563.0 697374.0 87466.6 89219.0 91307.5 91412.5 91747.0 506.9 518.5 518.9 519.5 519.7 10810.2 10833.9 10916.3 10978.7 10981.5 37178.0 37537.2 37913.0 38651.3 38890.6 46665.3 47999.3 49060.0 49705.0 52143.0 10251.9 10433.1 10522.4 10569.1 10586.4 12375.7 14563.4 17512.3 17703.0 18093.7 14550.8 14718.4 14767.9 14848.3 14939.6 14506.6 14656.0 14723.8 15133.7 15215.8 14973.7 14990.3 15277.6 15413.0 15496.5 9648.3 10135.2 10423.2 10426.2 10439.1 10571.8 10618.5 13991.5 14489.3 15239.4 08:05:49 INFO - 2016-01-21 08:05:49,352 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,352 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5550.71 Median:6712.23 stddev:2864.27 (42.7%) stddev-sans-first:2856.29 08:05:49 INFO - 2016-01-21 08:05:49,352 DEBUG : BROWSER_OUTPUT: Values: 1520.2 1523.5 1603.5 1803.9 1828.5 7022.2 7597.2 7860.1 7867.3 7917.5 7765.6 7999.1 8008.5 8011.2 8016.4 1974.9 2020.7 2215.6 2260.6 2292.1 2297.9 2299.5 2307.1 2308.8 2309.4 2186.1 2270.8 2271.7 2272.8 2274.7 2252.1 2269.3 2271.4 2274.9 2275.4 2282.6 2288.6 2315.2 2317.9 2320.0 7998.4 8064.8 8074.4 8077.9 8079.9 4779.8 4846.2 4877.9 4878.8 4887.3 3098.6 3123.4 3152.8 3190.4 3211.4 1017.8 1034.2 1044.5 1066.2 1078.5 11186.4 11203.0 11222.4 11223.1 11230.9 4295.3 4304.6 4369.6 4371.9 4390.2 9276.6 9281.7 9288.2 9292.8 9309.1 4881.6 4897.8 4899.3 4902.4 4904.4 4449.9 4470.3 4481.9 4487.3 4487.8 3515.9 3544.1 3555.6 3604.5 3781.5 1222.3 1226.1 1227.0 1229.3 1247.3 1101.2 1105.3 1112.7 1295.8 1389.0 7421.1 7459.0 7486.0 7522.8 7543.2 1065.3 1078.3 1110.6 1116.2 1147.9 5915.3 5967.9 5976.3 5980.0 5984.9 9171.0 9291.8 9404.5 9524.4 9537.4 9246.5 9288.4 9401.4 9412.0 9420.6 7270.9 7314.8 7342.3 7381.9 7401.7 6964.7 6989.4 6998.7 7002.4 7003.3 8231.7 8246.0 8378.7 8423.1 8423.1 8112.7 8224.5 8314.4 8364.8 8377.2 6746.3 6975.8 6979.5 7023.4 7036.4 6967.5 7092.9 7427.9 7716.6 7720.4 7246.2 7351.9 7359.9 7406.0 7530.2 7200.0 7210.9 7280.0 7329.7 7398.3 6881.3 6908.5 6933.3 7176.7 7180.1 6690.4 6734.0 6747.0 6749.2 6759.1 6855.4 6870.5 6896.1 6905.9 6976.8 08:05:49 INFO - 2016-01-21 08:05:49,352 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,352 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5521.59 Median:6326.59 stddev:1632.87 (25.8%) stddev-sans-first:1590.91 08:05:49 INFO - 2016-01-21 08:05:49,354 DEBUG : BROWSER_OUTPUT: Values: 364.5 365.8 371.8 372.6 378.5 6161.6 6174.4 6177.4 6187.1 6233.8 6120.6 6129.4 6132.5 6145.1 6148.5 4730.3 4750.7 4786.9 4800.3 4818.2 4789.4 4800.4 4806.4 4808.7 4813.4 4473.0 4552.2 4748.6 4750.8 4758.2 4643.9 4724.8 4753.5 4763.8 4764.4 4181.4 4197.5 4205.4 4211.6 4219.2 5630.4 5704.0 5783.0 5862.6 5918.7 5508.0 5511.3 5514.6 5519.4 5520.6 6487.8 6524.3 6537.9 6538.1 6541.0 4551.4 4634.7 4642.0 4658.2 4675.1 6772.5 6774.1 6785.1 6785.3 6791.9 4743.1 4792.9 4798.8 4799.0 4800.0 4856.0 4858.1 4859.9 4862.1 4863.5 5375.8 5477.3 5477.8 5493.5 5528.0 6566.1 6570.0 6576.8 6580.2 6581.0 1716.9 1733.2 1735.7 1738.3 1741.1 1188.1 1197.8 1199.1 1200.1 1229.0 6379.5 6394.4 6396.7 6402.2 6403.5 6769.4 6773.7 6780.8 6783.7 6785.6 6297.4 6355.8 6403.2 6406.9 6408.1 3649.1 3662.1 3682.2 3683.8 3684.4 6614.8 6796.0 6886.6 6894.2 6895.7 6717.0 6896.4 6935.3 6962.8 7000.8 6976.2 6978.1 6981.1 6983.4 6985.0 6725.6 6822.3 6826.5 6842.5 6844.7 6719.2 6725.8 6727.0 6741.7 6745.3 6927.5 6933.6 6943.9 6948.8 6990.1 4785.6 4844.4 4853.9 4862.8 4870.3 6804.4 6806.4 6806.7 6815.2 6826.3 6649.9 6655.8 6663.6 6666.5 6669.2 6531.2 6640.7 6649.4 6649.8 6660.0 6546.6 6548.1 6554.1 6566.3 6567.6 6657.6 6659.7 6665.4 6669.2 6669.9 6357.8 6494.5 6502.5 6502.9 6514.8 08:05:49 INFO - 2016-01-21 08:05:49,354 DEBUG : BROWSER_OUTPUT: 08:05:49 INFO - 2016-01-21 08:05:49,354 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14356.34 Median:44944.77 stddev:16165.46 (36.0%) stddev-sans-first:16178.58 08:05:49 INFO - 2016-01-21 08:05:49,354 DEBUG : BROWSER_OUTPUT: Values: 763.7 770.7 894.5 900.7 907.7 14340.9 14603.3 14628.0 14634.8 14666.5 13756.9 13832.6 13935.8 13954.7 13970.0 756.4 766.4 864.3 896.6 919.9 915.1 927.7 929.2 929.9 930.5 943.9 954.1 961.5 962.6 963.0 801.5 801.8 941.8 946.2 953.1 705.0 707.2 707.7 708.9 709.6 13588.1 13743.4 13747.8 13756.2 13763.2 12359.9 12548.0 12683.4 13000.7 13038.9 10610.8 10643.9 10824.9 10877.3 11419.9 3704.5 3836.0 3854.8 3888.3 3907.6 49048.5 49397.0 49542.3 49788.5 50077.5 10992.0 11086.5 11097.7 11476.9 11644.1 19049.4 19200.8 19204.3 19241.0 19264.1 7755.7 7802.9 8085.4 8135.6 8193.5 4290.0 4540.9 4573.2 4574.2 4652.3 3180.1 3189.3 3194.6 3200.0 3205.6 565.7 581.8 582.9 592.8 597.1 629.4 707.4 716.4 737.4 748.8 8704.6 8941.7 9012.1 9095.8 9162.9 595.2 697.7 699.6 729.2 763.3 3833.4 3889.3 3910.7 4020.2 4021.7 58171.0 58338.1 58587.4 59096.7 60158.4 48635.0 49650.8 49875.8 49930.3 50126.5 44851.8 45037.8 45298.3 46497.3 46839.8 9101.2 9174.8 9249.5 9362.9 9493.9 36528.8 37261.3 37698.0 38005.6 38148.6 46730.0 46930.3 46977.0 47479.5 47777.0 11811.2 11862.7 11990.6 12054.5 12376.7 12910.2 13003.2 13023.2 13155.9 13431.4 12301.0 12631.9 12889.4 13270.5 13382.3 11446.3 11461.8 11611.8 11914.6 11953.5 11990.2 12134.6 12198.1 12278.3 12769.6 11037.5 11065.4 11124.9 11215.2 11273.9 9296.1 10109.1 10121.3 10214.8 10632.6 08:05:49 INFO - 2016-01-21 08:05:49,354 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:05:49 INFO - 2016-01-21 08:05:49,354 DEBUG : BROWSER_OUTPUT: 08:05:50 INFO - 2016-01-21 08:05:50,022 INFO : Browser exited with error code: 0 08:05:50 INFO - 2016-01-21 08:05:50,045 INFO : Completed test dromaeo_css (00:18:31) 08:05:50 INFO - 2016-01-21 08:05:50,045 INFO : Starting test kraken 08:05:50 INFO - 2016-01-21 08:05:50,045 DEBUG : operating with platform_type : linux_ 08:05:50 INFO - 2016-01-21 08:05:50,045 INFO : Initialising browser for kraken test... 08:05:50 INFO - 2016-01-21 08:05:50,051 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRcCabB/profile http://localhost:48981/getInfo.html 08:05:53 INFO - 2016-01-21 08:05:53,730 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:05:53 INFO - 2016-01-21 08:05:53,730 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:05:53 INFO - 2016-01-21 08:05:53,730 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:05:53 INFO - 2016-01-21 08:05:53,730 DEBUG : BROWSER_OUTPUT: __metrics 08:05:53 INFO - 2016-01-21 08:05:53,867 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:05:54 INFO - 2016-01-21 08:05:54,922 INFO : Browser initialized. 08:05:54 INFO - 2016-01-21 08:05:54,923 INFO : Running cycle 1/1 for kraken test... 08:05:54 INFO - 2016-01-21 08:05:54,923 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRcCabB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:06:06 INFO - 2016-01-21 08:06:06,754 DEBUG : BROWSER_OUTPUT: RSS: Main: 135950336 08:06:06 INFO - 2016-01-21 08:06:06,754 DEBUG : BROWSER_OUTPUT: 08:06:09 INFO - 2016-01-21 08:06:09,221 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48981/tests/kraken/driver.html?testName=audio-beat-detection) 08:06:09 INFO - 2016-01-21 08:06:09,791 DEBUG : BROWSER_OUTPUT: RSS: Main: 169619456 08:06:09 INFO - 2016-01-21 08:06:09,791 DEBUG : BROWSER_OUTPUT: 08:06:12 INFO - 2016-01-21 08:06:12,367 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48981/tests/kraken/driver.html?testName=audio-dft) 08:06:12 INFO - 2016-01-21 08:06:12,934 DEBUG : BROWSER_OUTPUT: RSS: Main: 167546880 08:06:12 INFO - 2016-01-21 08:06:12,934 DEBUG : BROWSER_OUTPUT: 08:06:15 INFO - 2016-01-21 08:06:15,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48981/tests/kraken/driver.html?testName=audio-fft) 08:06:16 INFO - 2016-01-21 08:06:16,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 165670912 08:06:16 INFO - 2016-01-21 08:06:16,287 DEBUG : BROWSER_OUTPUT: 08:06:18 INFO - 2016-01-21 08:06:18,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48981/tests/kraken/driver.html?testName=audio-oscillator) 08:06:18 INFO - 2016-01-21 08:06:18,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 165351424 08:06:18 INFO - 2016-01-21 08:06:18,841 DEBUG : BROWSER_OUTPUT: 08:06:21 INFO - 2016-01-21 08:06:21,020 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48981/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:06:21 INFO - 2016-01-21 08:06:21,581 DEBUG : BROWSER_OUTPUT: RSS: Main: 164274176 08:06:21 INFO - 2016-01-21 08:06:21,581 DEBUG : BROWSER_OUTPUT: 08:06:24 INFO - 2016-01-21 08:06:24,465 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48981/tests/kraken/driver.html?testName=imaging-darkroom) 08:06:25 INFO - 2016-01-21 08:06:25,032 DEBUG : BROWSER_OUTPUT: RSS: Main: 162250752 08:06:25 INFO - 2016-01-21 08:06:25,032 DEBUG : BROWSER_OUTPUT: 08:06:28 INFO - 2016-01-21 08:06:28,353 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48981/tests/kraken/driver.html?testName=imaging-desaturate) 08:06:28 INFO - 2016-01-21 08:06:28,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 164261888 08:06:28 INFO - 2016-01-21 08:06:28,919 DEBUG : BROWSER_OUTPUT: 08:06:31 INFO - 2016-01-21 08:06:31,629 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48981/tests/kraken/driver.html?testName=json-parse-financial) 08:06:32 INFO - 2016-01-21 08:06:32,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 164847616 08:06:32 INFO - 2016-01-21 08:06:32,195 DEBUG : BROWSER_OUTPUT: 08:06:33 INFO - 2016-01-21 08:06:33,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48981/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:06:34 INFO - 2016-01-21 08:06:34,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 174891008 08:06:34 INFO - 2016-01-21 08:06:34,306 DEBUG : BROWSER_OUTPUT: 08:06:36 INFO - 2016-01-21 08:06:36,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:06:36 INFO - 2016-01-21 08:06:36,691 DEBUG : BROWSER_OUTPUT: RSS: Main: 170856448 08:06:36 INFO - 2016-01-21 08:06:36,692 DEBUG : BROWSER_OUTPUT: 08:06:38 INFO - 2016-01-21 08:06:38,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:06:39 INFO - 2016-01-21 08:06:39,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 178593792 08:06:39 INFO - 2016-01-21 08:06:39,230 DEBUG : BROWSER_OUTPUT: 08:06:41 INFO - 2016-01-21 08:06:41,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:06:42 INFO - 2016-01-21 08:06:42,187 DEBUG : BROWSER_OUTPUT: RSS: Main: 181772288 08:06:42 INFO - 2016-01-21 08:06:42,187 DEBUG : BROWSER_OUTPUT: 08:06:44 INFO - 2016-01-21 08:06:44,674 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:06:45 INFO - 2016-01-21 08:06:45,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 181927936 08:06:45 INFO - 2016-01-21 08:06:45,255 DEBUG : BROWSER_OUTPUT: 08:06:46 INFO - 2016-01-21 08:06:46,908 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48981/tests/kraken/driver.html?testName=ai-astar) 08:06:47 INFO - 2016-01-21 08:06:47,158 DEBUG : BROWSER_OUTPUT: RSS: Main: 238960640 08:06:47 INFO - 2016-01-21 08:06:47,158 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,160 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:06:47 INFO - 2016-01-21 08:06:47,160 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:06:47 INFO - 2016-01-21 08:06:47,160 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:06:47 INFO - 2016-01-21 08:06:47,161 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:06:47 INFO - 2016-01-21 08:06:47,161 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;103;108;109;109;100;109;108;110 08:06:47 INFO - 2016-01-21 08:06:47,161 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;124;153;159;128;148;148;123;165;123 08:06:47 INFO - 2016-01-21 08:06:47,161 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;160;161;161;160;161;160;160;161 08:06:47 INFO - 2016-01-21 08:06:47,162 DEBUG : BROWSER_OUTPUT: |3;audio-fft;75;71;72;155;70;71;72;73;149;72 08:06:47 INFO - 2016-01-21 08:06:47,162 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;141;91;92;92;136;92;93;135 08:06:47 INFO - 2016-01-21 08:06:47,162 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;110;109;109;109;109;109 08:06:47 INFO - 2016-01-21 08:06:47,162 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;144;145;147;146;145;146;147;146 08:06:47 INFO - 2016-01-21 08:06:47,162 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;96;96;96;95;95;95;95 08:06:47 INFO - 2016-01-21 08:06:47,162 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;57;56;57;57;57;58;58;54 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;65;65;64;65;65;65;65 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;78;79;80;79;80;80;80;80;80 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;116;115;115;127;109;121;114;117;127 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;144;146;144;144;134;146;143;141;147 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;67;66;66;66;65;65;66;67;66 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:06:47 INFO - 2016-01-21 08:06:47,163 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:06:47 INFO - 2016-01-21 08:06:47,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,885 08:06:47 INFO - 2016-01-21 08:06:47,164 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:06:47 INFO - 2016-01-21 08:06:47,164 DEBUG : BROWSER_OUTPUT: __startTimestamp1453392407159__endTimestamp 08:06:47 INFO - 2016-01-21 08:06:47,164 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,167 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:06:47 INFO - 2016-01-21 08:06:47,168 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:06:47 INFO - 2016-01-21 08:06:47,168 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,168 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.40 Median:109.00 stddev:3.24 (3.0%) stddev-sans-first:3.38 08:06:47 INFO - 2016-01-21 08:06:47,168 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 103.0 108.0 109.0 109.0 100.0 109.0 108.0 110.0 08:06:47 INFO - 2016-01-21 08:06:47,168 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,169 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:148.50 stddev:15.99 (10.8%) stddev-sans-first:16.76 08:06:47 INFO - 2016-01-21 08:06:47,169 DEBUG : BROWSER_OUTPUT: Values: 149.0 124.0 153.0 159.0 128.0 148.0 148.0 123.0 165.0 123.0 08:06:47 INFO - 2016-01-21 08:06:47,169 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,169 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 08:06:47 INFO - 2016-01-21 08:06:47,169 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 160.0 161.0 161.0 160.0 161.0 160.0 160.0 161.0 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.00 Median:72.00 stddev:33.79 (46.9%) stddev-sans-first:35.51 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: Values: 75.0 71.0 72.0 155.0 70.0 71.0 72.0 73.0 149.0 72.0 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.60 Median:92.00 stddev:21.96 (23.9%) stddev-sans-first:22.73 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 141.0 91.0 92.0 92.0 136.0 92.0 93.0 135.0 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 08:06:47 INFO - 2016-01-21 08:06:47,170 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.97 (0.7%) stddev-sans-first:1.00 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 144.0 145.0 147.0 146.0 145.0 146.0 147.0 146.0 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.26 (2.2%) stddev-sans-first:1.20 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 57.0 56.0 57.0 57.0 57.0 58.0 58.0 54.0 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 65.0 65.0 64.0 65.0 65.0 65.0 65.0 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:80.00 stddev:0.71 (0.9%) stddev-sans-first:0.73 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: Values: 79.0 78.0 79.0 80.0 79.0 80.0 80.0 80.0 80.0 80.0 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,171 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.90 Median:117.50 stddev:5.69 (4.8%) stddev-sans-first:6.03 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: Values: 118.0 116.0 115.0 115.0 127.0 109.0 121.0 114.0 117.0 127.0 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.40 Median:144.00 stddev:4.50 (3.1%) stddev-sans-first:3.90 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: Values: 135.0 144.0 146.0 144.0 144.0 134.0 146.0 143.0 141.0 147.0 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 66.0 66.0 66.0 65.0 65.0 66.0 67.0 66.0 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:06:47 INFO - 2016-01-21 08:06:47,172 DEBUG : BROWSER_OUTPUT: 08:06:47 INFO - 2016-01-21 08:06:47,974 INFO : Browser exited with error code: 0 08:06:48 INFO - 2016-01-21 08:06:48,025 INFO : Completed test kraken (00:00:57) 08:06:48 INFO - 2016-01-21 08:06:48,025 INFO : Starting test v8_7 08:06:48 INFO - 2016-01-21 08:06:48,025 DEBUG : operating with platform_type : linux_ 08:06:48 INFO - 2016-01-21 08:06:48,025 INFO : Initialising browser for v8_7 test... 08:06:48 INFO - 2016-01-21 08:06:48,031 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkAmjns/profile http://localhost:48981/getInfo.html 08:06:51 INFO - 2016-01-21 08:06:51,928 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:06:51 INFO - 2016-01-21 08:06:51,929 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:06:51 INFO - 2016-01-21 08:06:51,929 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:06:51 INFO - 2016-01-21 08:06:51,929 DEBUG : BROWSER_OUTPUT: __metrics 08:06:52 INFO - 2016-01-21 08:06:52,053 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:06:52 INFO - 2016-01-21 08:06:52,952 INFO : Browser initialized. 08:06:52 INFO - 2016-01-21 08:06:52,952 INFO : Running cycle 1/1 for v8_7 test... 08:06:52 INFO - 2016-01-21 08:06:52,952 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkAmjns/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:07:04 INFO - 2016-01-21 08:07:04,660 DEBUG : BROWSER_OUTPUT: RSS: Main: 137732096 08:07:04 INFO - 2016-01-21 08:07:04,660 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,035 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48981/tests/v8_7/run.html (next: http://localhost:48981/tests/v8_7/run.html) 08:07:26 INFO - 2016-01-21 08:07:26,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 427196416 08:07:26 INFO - 2016-01-21 08:07:26,285 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,287 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:07:26 INFO - 2016-01-21 08:07:26,287 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:07:26 INFO - 2016-01-21 08:07:26,287 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:07:26 INFO - 2016-01-21 08:07:26,287 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:07:26 INFO - 2016-01-21 08:07:26,287 DEBUG : BROWSER_OUTPUT: |0;Richards;164.74464579901152 08:07:26 INFO - 2016-01-21 08:07:26,287 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;173.3102253032929 08:07:26 INFO - 2016-01-21 08:07:26,288 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 08:07:26 INFO - 2016-01-21 08:07:26,288 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 08:07:26 INFO - 2016-01-21 08:07:26,288 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.0229445506692 08:07:26 INFO - 2016-01-21 08:07:26,288 DEBUG : BROWSER_OUTPUT: |5;Earley;553.0973451327434 08:07:26 INFO - 2016-01-21 08:07:26,288 DEBUG : BROWSER_OUTPUT: |6;Boyer;10945.652173913044 08:07:26 INFO - 2016-01-21 08:07:26,288 DEBUG : BROWSER_OUTPUT: |7;RegExp;26315.78947368421 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: |8;Splay;504.2864346949067 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: __startTimestamp1453392446285__endTimestamp 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,289 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: Values: 164.7 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:173.31 Median:173.31 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: Values: 173.3 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,291 DEBUG : BROWSER_OUTPUT: Values: 278.8 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: Values: 5202.1 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.02 Median:956.02 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: Values: 956.0 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.10 Median:553.10 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: Values: 553.1 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10945.65 Median:10945.65 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: Values: 10945.7 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26315.79 Median:26315.79 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: Values: 26315.8 08:07:26 INFO - 2016-01-21 08:07:26,292 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:504.29 Median:504.29 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: Values: 504.3 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: Values: 4850.2 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:07:26 INFO - 2016-01-21 08:07:26,293 DEBUG : BROWSER_OUTPUT: 08:07:27 INFO - 2016-01-21 08:07:27,118 INFO : Browser exited with error code: 0 08:07:27 INFO - 2016-01-21 08:07:27,132 INFO : Completed test v8_7 (00:00:39) 08:07:27 INFO - 2016-01-21 08:07:27,336 INFO : Completed test suite (00:20:08) 08:07:27 INFO - 2016-01-21 08:07:27,336 DEBUG : Working with test: dromaeo_css 08:07:27 INFO - 2016-01-21 08:07:27,336 DEBUG : Generating results file: dromaeo_css 08:07:27 INFO - 2016-01-21 08:07:27,337 DEBUG : Working with test: kraken 08:07:27 INFO - 2016-01-21 08:07:27,337 DEBUG : Generating results file: kraken 08:07:27 INFO - 2016-01-21 08:07:27,338 INFO : javascript benchmark 08:07:27 INFO - 2016-01-21 08:07:27,338 DEBUG : Working with test: v8_7 08:07:27 INFO - 2016-01-21 08:07:27,338 DEBUG : Generating results file: v8_7 08:07:27 INFO - 2016-01-21 08:07:27,338 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:07:27 INFO - 2016-01-21 08:07:27,392 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 08:07:27 INFO - 2016-01-21 08:07:27,392 DEBUG : process_Request line: dromaeo_css 7407.21 graph.html#tests=[[72,52,35]] 08:07:27 INFO - 2016-01-21 08:07:27,393 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:07:27 INFO - 2016-01-21 08:07:27,441 DEBUG : process_Request line: kraken 1481.00 graph.html#tests=[[232,52,35]] 08:07:27 INFO - 2016-01-21 08:07:27,441 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:07:27 INFO - 2016-01-21 08:07:27,483 DEBUG : process_Request line: v8_7 22943.77 graph.html#tests=[[230,52,35]] 08:07:27 INFO - 2016-01-21 08:07:27,484 INFO : javascript benchmark 08:07:27 INFO - 2016-01-21 08:07:27,487 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3186.522021696745, 3219.597550306212, 3251.528528363389, 3254.2677897473072, 3267.6568960259174, 5388.434214407484, 5414.377346605142, 5421.268128802611, 5427.592930530206, 5444.128939369702, 5462.836114916549, 5465.617406781527, 5518.448155184479, 5520.475554822286, 5525.4474552544725, 4918.877028074291, 5011.574016208618, 5011.599072074244, 5057.544820966113, 5090.160123579623, 4949.653524253304, 5041.873953151164, 5120.92318615221, 5121.820736274231, 5122.129238029544, 4961.652684312099, 5047.823326183584, 5049.67177133487, 5078.64449488536, 5082.745862706868, 5106.412762532319, 5159.277701121846, 5169.638125331228, 5175.896482070338, 5195.948040519603, 4850.708957462563, 4876.0, 4951.603871690292, 5059.924101138486, 5061.468545802712, 5415.53967912724, 5450.31871016123, 5481.698506582136, 5482.095454250044, 5483.8080667176455, 5976.402359763989, 5984.581079324449, 6006.789762358297, 6031.24609423822, 6034.155218269446, 5811.912821307684, 5838.41615838417, 5897.6166549174595, 5917.171595976523, 6064.151018857364, 5460.945390546083, 5461.371942226655, 5512.2558454608115, 5524.419935906712, 5584.91622625661, 5964.761409543657, 5967.701614919237, 5973.074173503114, 5978.163057172, 5986.610870293417, 5678.801241956512, 5788.88422231553, 5790.826275211666, 5877.794277200277, 5920.703964801743, 5965.492933100648, 5966.671833049224, 5980.700964951735, 5983.222181116427, 5986.341502434729, 5711.6858572778065, 5893.44012318829, 5938.703064846741, 5956.017257152608, 5956.076808094752, 5783.942160578425, 5788.942110578924, 5858.53131749459, 5858.912116318259, 5872.383399743051, 4555.06621142665, 4577.496475387705, 4593.265077587636, 4593.747343896051, 4623.606993405531, 3984.163325701635, 4058.127502586958, 4084.7753373564738, 4103.076807718239, 4130.215259100762, 5498.120300751861, 5541.695206763586, 5564.554835613141, 5567.192757050205, 5585.357683866326, 5978.3124940631515, 6021.307549631849, 6021.879562408727, 6026.5178785697035, 6061.181740465069, 5410.567154627621, 5438.320209973754, 5522.061249587579, 5696.145578163226, 5736.569757268222, 5175.275461435402, 5188.688678679291, 5190.351206099238, 5192.039472697563, 5198.740062996835, 6112.969435152885, 6113.510919126459, 6119.5104391648565, 6126.23422072241, 6126.448619624255, 5659.943400566106, 5772.56705747079, 5866.67733274674, 6001.939980600313, 6029.969850150721, 6107.603005804608, 6120.44915957566, 6123.08153776937, 6123.7550497981365, 6130.478909292582, 5878.588498805043, 5917.437843404869, 5923.289205295392, 5928.644281343133, 5930.7627694893445, 5530.253415788817, 5684.090545512699, 5889.057750760029, 5980.282366116094, 6014.458698717136, 5825.854353641193, 5839.620424672383, 5840.766369345179, 5843.29880414353, 5845.357010728731, 5840.532757379315, 5856.502197313276, 5858.091995740667, 5861.853453663522, 5863.237779088691, 5610.13042978339, 5682.715864206856, 5710.17202505626, 5781.595288329777, 5801.709914504342, 5902.852428689317, 5903.881922361443, 5904.793332233351, 5906.40935906406, 5906.557008224315, 5718.342390625131, 5767.5674324425, 5772.451617096369, 5772.740226689725, 5787.189793428839], "unit": "ms", "name": "dojo.html", "value": 5464.826815356838}, {"lowerIsBetter": false, "replicates": [572.9054705973512, 614.2352770800353, 622.4087117238624, 622.7104396455649, 623.8689875126223, 30483.237919052033, 37986.62013379866, 38343.42484862729, 38395.0, 38430.80784596073, 44990.55009449904, 45726.314105288446, 45858.770706146424, 45975.54024459763, 46097.539024609745, 8009.839803203932, 8120.878186827203, 8135.308498777611, 8952.731418057469, 9130.26957843371, 10839.13286937043, 11035.0, 11047.447627618587, 11047.558097676083, 11050.723731906726, 10811.0, 10887.83668244977, 10932.398718070503, 10933.56265749369, 10953.397563134024, 8269.0, 8450.661973521052, 10777.892221077846, 10798.190135739811, 11003.834942475869, 10528.789424211549, 10674.0, 10675.306105103222, 10679.41263230522, 10683.893161068367, 42565.78717106426, 42587.78706106481, 42782.0, 42999.78500107479, 43003.569964300266, 3609.205974685565, 3691.354013047706, 3706.8517259309865, 3732.5520937487404, 3777.7166712496673, 30045.248868778235, 30287.69712302871, 30513.084607461813, 30689.69310306891, 31199.532007019912, 4255.510616279136, 4284.357346398034, 4339.435873336478, 4345.521992580813, 4358.36803663467, 278219.0, 282037.99999999796, 282157.0, 282849.0, 283678.0, 58955.11567326493, 60240.397596024355, 65862.67068664626, 66188.0, 72885.6355718218, 66194.66902665456, 66323.0, 66398.33601663921, 66874.66562667253, 66967.66516167388, 14233.28833558318, 14403.0, 14435.639109022359, 14499.565013049414, 14535.563933082025, 11264.098872090213, 11321.320720756781, 11337.376444295478, 11433.714157146138, 11440.427978601037, 22305.330840074825, 22439.32682019542, 22702.205422810122, 22718.886405567868, 22757.772422275895, 8524.40329176964, 8585.699500517578, 8629.913700862911, 8785.956070219736, 8814.471131732116, 992.4194346307347, 1028.6554004408606, 1035.316690983947, 1102.4542851288663, 1118.9608363707393, 29221.0, 29459.410811784073, 30177.0, 30202.848985754932, 30484.69515304863, 1073.897979691928, 1086.9565217391216, 1087.983680244797, 1107.9390633515234, 1182.2374568403332, 14507.0, 14905.180215088058, 15016.774748378783, 16701.41545045942, 17460.30158793634, 414983.0, 420739.0, 422064.0, 422209.0, 422580.0, 57554.0, 57945.71027144837, 58045.709771451715, 58051.12923306154, 58055.419445805004, 1327.6216278360814, 1378.4830688491816, 1435.9856401435852, 1467.8404060792068, 1477.364733164728, 29736.0, 29939.251518712208, 33319.0, 33972.83013584916, 34725.47911781376, 44887.77556112198, 45567.31649025201, 54215.18677219766, 54722.45277547333, 55015.44984550103, 306183.0, 309145.0, 310689.0, 311057.0, 311859.0, 44256.0, 44810.0, 45145.32282015707, 45192.774036130926, 45765.542344576126, 48447.5155248443, 48790.75604621954, 49296.50703492919, 49780.502194977584, 50152.24771628356, 40801.0, 41698.58301416947, 41946.37080443735, 41979.0, 42902.14195716131, 40680.0, 41508.79245603873, 41603.37594936139, 41894.790526047174, 42282.0, 40313.59686403099, 40479.0, 40490.79754601208, 40936.0, 44047.77976110099, 31895.521567176045, 32179.51730724088, 34087.318253634294, 34325.0, 34823.12942176466, 26844.463110737284, 26947.595786062833, 27011.729882701708, 27174.456510870066, 27213.86393068022], "unit": "ms", "name": "ext.html", "value": 20335.613086356032}, {"lowerIsBetter": false, "replicates": [388.4251308064065, 390.05995550722764, 391.17071807767536, 395.1366264711607, 396.73815281913943, 14065.367058482367, 17582.0, 20004.0, 20010.29963951262, 20535.897320513377, 18183.636327273445, 18191.636167276647, 18556.814431855677, 18609.813901860976, 18618.34835780748, 6095.573309868311, 6203.596766210205, 6204.224471941007, 6208.6585237811905, 6211.47202487789, 6116.143739876398, 6278.780242691507, 6312.242530896299, 6325.304216536178, 6326.620402775849, 4517.932231016537, 4642.303654451825, 6285.654289014125, 6289.716962736665, 6299.622022678631, 6757.121574195372, 6987.82530436738, 6995.755148569776, 7041.225465198824, 7043.225245222996, 6624.867502649969, 6789.25318214996, 6793.218779840332, 6794.252632210453, 6795.320467953215, 22180.334589962327, 22504.662430063563, 22641.77358226413, 23165.884170579207, 23683.763162368326, 2527.2670925431603, 2542.605896086091, 2577.703564090135, 2634.2624065261757, 2698.3119304577403, 14781.33483993223, 15153.772693409608, 15245.085294882232, 15538.834587405876, 15602.141882196474, 2151.171798857451, 2209.823214142865, 2223.7998580127864, 2238.507528343762, 2288.1305104060516, 66892.0, 72318.63840680763, 72359.0, 73620.0, 74050.62974685145, 16426.260818262966, 16474.093924834247, 16499.257533411026, 16549.751753723704, 16621.83378166227, 25868.22395328143, 25932.481350372887, 26060.739392605832, 26145.869270653904, 26236.0, 9341.252699784136, 9376.0, 9429.339946203838, 9489.38319009276, 9501.667441639509, 7648.541087534766, 7657.693692252247, 7669.463137580316, 7670.731524396731, 7728.18854020331, 8024.879626805603, 8117.675292988214, 8121.959390203129, 8175.63209655567, 8198.42611017235, 1995.5410255640993, 2314.2710046335687, 3098.721115099652, 3166.762492813048, 3171.349873276019, 906.7053207707521, 955.6082006377352, 958.3195930889008, 1017.6387382479261, 1039.750459889621, 17134.657306854042, 17373.82626173722, 17512.0, 17838.19728112212, 17948.192331345123, 477.45093142885264, 482.9251466022772, 492.8496808473378, 516.5738265930622, 1039.6257347354947, 6190.473809726223, 6256.937430625727, 6267.435930766253, 6290.119383286344, 6337.9366206337345, 393092.0, 396034.0, 600761.0, 689563.0, 697374.0, 87466.56266718626, 89219.0, 91307.54346228226, 91412.54293728489, 91747.0, 506.9062223488593, 518.4633903909511, 518.8832512684692, 519.451978163034, 519.6881870877444, 10810.243282970117, 10833.945830270799, 10916.290441121302, 10978.725531861448, 10981.505832237408, 37178.0, 37537.2492550153, 37913.0, 38651.32812382819, 38890.6110938887, 46665.3000205004, 47999.280010799164, 49060.0, 49705.0, 52143.0, 10251.94874025655, 10433.113185379329, 10522.421266830397, 10569.101626361846, 10586.364818110938, 12375.69060773488, 14563.417463301774, 17512.299508019536, 17703.0, 18093.728594071363, 14550.781738273723, 14718.411263549338, 14767.92616036913, 14848.257587120817, 14939.626509337353, 14506.564803055926, 14656.0, 14723.779143312644, 15133.697326053638, 15215.847841521443, 14973.700525989638, 14990.325435355653, 15277.6180595486, 15413.0, 15496.535103946899, 9648.276379271723, 10135.189184865341, 10423.166146708396, 10426.165906727596, 10439.112675422673, 10571.788564228518, 10618.469076545987, 13991.510297139552, 14489.347979340744, 15239.3904243829], "unit": "ms", "name": "jquery.html", "value": 9712.986482498358}, {"lowerIsBetter": false, "replicates": [1520.2474774986383, 1523.5353217268735, 1603.5269595469335, 1803.9458816235513, 1828.5337239004052, 7022.192447868497, 7597.164311925684, 7860.0960889497655, 7867.2919437250775, 7917.485363451386, 7765.563370776396, 7999.120096789363, 8008.479448835822, 8011.158828323015, 8016.358691304697, 1974.9308774192907, 2020.6867935469952, 2215.601191785478, 2260.570491606598, 2292.128990983424, 2297.8621282723007, 2299.4711216420233, 2307.1232931486097, 2308.849924754894, 2309.3533810532995, 2186.0599942024924, 2270.795628393444, 2271.7273927128767, 2272.8295377846644, 2274.6815445837597, 2252.076648574085, 2269.319204238738, 2271.375371772759, 2274.9090036398525, 2275.442516583431, 2282.589133955887, 2288.5651726171973, 2315.23597212921, 2317.8725170115636, 2320.048780000209, 7998.440109192359, 8064.758057258292, 8074.4347895647325, 8077.919220807776, 8079.878801817978, 4779.784909679072, 4846.151923413388, 4877.8780530486665, 4878.829240976549, 4887.315775791392, 3098.643655979546, 3123.375324935022, 3152.763542734281, 3190.441672707267, 3211.3577284542953, 1017.7557386227326, 1034.2398337222135, 1044.4777611194402, 1066.248294952057, 1078.4661592511757, 11186.384748838811, 11203.0, 11222.438878056064, 11223.102151827752, 11230.94384528072, 4295.334223195409, 4304.5910638489295, 4369.584889435499, 4371.934420983688, 4390.209762242827, 9276.62893484253, 9281.72154835356, 9288.164065234027, 9292.814143717223, 9309.115634014757, 4881.560659540587, 4897.8285759998225, 4899.2896030075435, 4902.362692849907, 4904.43598986114, 4449.888752781141, 4470.307102399133, 4481.887952801141, 4487.259602165672, 4487.753173575485, 3515.8945231643092, 3544.0962554548423, 3555.6444355564754, 3604.5133906922756, 3781.470594116826, 1222.2849632964637, 1226.1478272600634, 1227.0429065328879, 1229.250157403983, 1247.2516490106045, 1101.2291396022815, 1105.3423213188141, 1112.7385064509833, 1295.831541899566, 1388.965275868091, 7421.1094668639025, 7458.962705186439, 7486.0, 7522.849543009219, 7543.207963163899, 1065.2809353686387, 1078.3475997021847, 1110.5613282753268, 1116.1659698934186, 1147.8909503597145, 5915.260592425947, 5967.850803729942, 5976.342602313742, 5980.0, 5984.940150598438, 9171.0, 9291.76770580741, 9404.482753448627, 9524.428534287965, 9537.380070295409, 9246.4914429707, 9288.442693438416, 9401.388909720847, 9411.952940235255, 9420.576074076684, 7270.927290727025, 7314.817129571803, 7342.265773422721, 7381.88927166093, 7401.740939067106, 6964.721411143498, 6989.405900498515, 6998.720051197895, 7002.404795592432, 7003.26465721092, 8231.670733170846, 8246.0, 8378.664853405795, 8423.07346191906, 8423.115572865006, 8112.716054938049, 8224.465409748347, 8314.376421768271, 8364.832703346021, 8377.162283771575, 6746.325367463214, 6975.825604359931, 6979.511434199558, 7023.438124950094, 7036.366726994595, 6967.512274140964, 7092.929070709227, 7427.925720742724, 7716.5755883426955, 7720.420968427503, 7246.232754164786, 7351.926480735338, 7359.852802944019, 7406.037215161994, 7530.209328020479, 7200.027996220443, 7210.89183662235, 7280.0, 7329.670164842488, 7398.334149926317, 6881.277465866212, 6908.48186386013, 6933.306669333027, 7176.677049532679, 7180.102487189101, 6690.431313338421, 6734.02356658272, 6747.0, 6749.190097188369, 6759.087523184504, 6855.383015528427, 6870.519063665495, 6896.103506544117, 6905.93094069073, 6976.790696279119], "unit": "ms", "name": "mootools.html", "value": 4566.906423399263}, {"lowerIsBetter": false, "replicates": [364.4951741837555, 365.84634453529515, 371.7787916189866, 372.6404020120583, 378.5325123472511, 6161.630302181867, 6174.413430724086, 6177.351378105302, 6187.133801267821, 6233.812985610428, 6120.602160859541, 6129.387061293868, 6132.509399248061, 6145.108959200913, 6148.5388595855375, 4730.293358108686, 4750.691205071659, 4786.904261914759, 4800.3279540864305, 4818.156822556056, 4789.401324834395, 4800.375951126349, 4806.399200099988, 4808.735519546423, 4813.350197723315, 4472.955270447286, 4552.226121559326, 4748.5726284634375, 4750.8337208197545, 4758.214894542396, 4643.86068417948, 4724.787384567703, 4753.524647535253, 4763.76181190939, 4764.404449443819, 4181.414601955728, 4197.538270790211, 4205.369194620831, 4211.578842115795, 4219.2194444027955, 5630.40880707528, 5703.971480142614, 5783.0, 5862.589618726691, 5918.733656985403, 5507.972460137714, 5511.283533140706, 5514.641548299388, 5519.365272993617, 5520.558355331604, 6487.83780405489, 6524.314946930552, 6537.901931471032, 6538.084668146464, 6540.967295163541, 4551.362809206714, 4634.721916684976, 4642.025174713314, 4658.208104622222, 4675.088357770236, 6772.525923185379, 6774.0854984576945, 6785.050092986986, 6785.287544807823, 6791.932080679179, 4743.075100355398, 4792.856214313576, 4798.808047678054, 4798.976005119952, 4799.952000480021, 4856.0, 4858.05267972749, 4859.927101093486, 4862.079478606001, 4863.537963893425, 5375.81184658535, 5477.315335583052, 5477.849651573135, 5493.478119578634, 5528.004959107318, 6566.146400967844, 6569.967150164219, 6576.816173088794, 6580.21037475506, 6580.967095164494, 1716.8969861808332, 1733.1767410479922, 1735.739639054152, 1738.2959901240017, 1741.0685283373334, 1188.1267268557604, 1197.8442802435802, 1199.0707201918583, 1200.099925056208, 1228.956986505468, 6379.489640828722, 6394.360563943568, 6396.744130234832, 6402.167718196698, 6403.48772098231, 6769.356911093537, 6773.695183716744, 6780.796576102724, 6783.660816959231, 6785.59286442815, 6297.370262973666, 6355.809325720235, 6403.199600049994, 6406.871862562816, 6408.070829729756, 3649.0694872807794, 3662.066173125836, 3682.226732386175, 3683.834227459771, 3684.3552378333684, 6614.768483103068, 6795.966020169966, 6886.586804791628, 6894.24163342032, 6895.655217239118, 6716.966415167893, 6896.379325860697, 6935.306469353024, 6962.7911162665205, 7000.824979375557, 6976.197737260179, 6978.127734033246, 6981.05755722981, 6983.441324694114, 6985.022096906438, 6725.596464212163, 6822.317768223138, 6826.522143449911, 6842.452603791784, 6844.657767111525, 6719.193696756421, 6725.764598239038, 6727.0, 6741.696623651849, 6745.291744366771, 6927.549709268881, 6933.618650974245, 6943.861122777617, 6948.756793512202, 6990.09128813251, 4785.641076919175, 4844.370231869975, 4853.902921941471, 4862.781174847211, 4870.342503761947, 6804.353586409283, 6806.42145417645, 6806.693698783665, 6815.216250131299, 6826.317368263134, 6649.867002659823, 6655.800325990228, 6663.566868153554, 6666.500012498985, 6669.199696036312, 6531.183602049744, 6640.734370625121, 6649.401553860176, 6649.833754156185, 6660.0, 6546.607203567801, 6548.116004339353, 6554.115194448879, 6566.343365663395, 6567.573107747981, 6657.600543967377, 6659.700313485808, 6665.400113989765, 6669.233038200669, 6669.933300666931, 6357.777477788255, 6494.512911531559, 6502.544821862424, 6502.934970650422, 6514.804555863332], "unit": "ms", "name": "prototype.html", "value": 5003.723733195014}, {"lowerIsBetter": false, "replicates": [763.6678045050403, 770.7495064104164, 894.4856707393249, 900.671254991928, 907.7276816954912, 14340.928295358523, 14603.269836508172, 14628.049176803514, 14634.780478292834, 14666.486672966448, 13756.93121534391, 13832.585022449342, 13935.790963135561, 13954.651133721636, 13970.0, 756.4288961833807, 766.3562607409775, 864.299917067176, 896.6144557840158, 919.9402038867486, 915.1169121797465, 927.6845872403381, 929.2380248196481, 929.9395539289958, 930.483581612205, 943.9197668198211, 954.1269738189549, 961.4856052012175, 962.5572236771059, 962.9711108666751, 801.4710291207804, 801.7795106345743, 941.8210539997378, 946.1957336264155, 953.132649289147, 704.9541779784349, 707.2432497227968, 707.7027648387638, 708.875946709329, 709.5529816215802, 13588.116772409761, 13743.381547830271, 13747.793783093262, 13756.243406612632, 13763.174209547458, 12359.93820030903, 12548.0, 12683.36583170847, 13000.674983125404, 13038.869611303955, 10610.840837387444, 10643.946780266126, 10824.945875270574, 10877.347359158397, 11419.942900285445, 3704.5369328833895, 3835.9808200959096, 3854.807259637007, 3888.2806680764147, 3907.648311651965, 49048.50951490475, 49397.0, 49542.256866147036, 49788.50211497875, 50077.49922500765, 10992.0, 11086.501107450023, 11097.72255693598, 11476.88523114775, 11644.126690498246, 19049.42851714423, 19200.80799192018, 19204.327848525234, 19241.0, 19264.133114009903, 7755.689772409153, 7802.882956755653, 8085.393595480363, 8135.633896474673, 8193.549354785544, 4289.978550107229, 4540.909181816345, 4573.176828170961, 4574.222382195035, 4652.325412815123, 3180.061881744891, 3189.2505261263586, 3194.6006749156354, 3200.0, 3205.583274174389, 565.6916980245776, 581.7527550790982, 582.909649004405, 592.7954855574819, 597.1013624495107, 629.3894921925724, 707.3598393453932, 716.3660160757663, 737.3584981066507, 748.7940816275568, 8704.564771761387, 8941.687040953535, 9012.098790121066, 9095.818083638422, 9162.908370916206, 595.214317101422, 697.7243988624465, 699.618707804248, 729.234303980818, 763.2520130272364, 3833.3674943633987, 3889.2804831106337, 3910.687145028391, 4020.2361551305744, 4021.678265738734, 58171.0, 58338.12492812611, 58587.414125859046, 59096.70451647714, 60158.398416016156, 48635.0, 49650.75174624104, 49875.75062124666, 49930.251046234334, 50126.498735012916, 44851.757412129264, 45037.77481112639, 45298.320525192146, 46497.30254046192, 46839.765801170775, 9101.180893719596, 9174.770630734285, 9249.537523123952, 9362.906370936204, 9493.857592135986, 36528.817355914114, 37261.254774903806, 37698.0, 38005.619943801314, 38148.61851381451, 46730.0, 46930.296045560026, 46977.0, 47479.52520474751, 47777.0, 11811.173217874666, 11862.703432414259, 11990.640280791591, 12054.457549410474, 12376.690582735504, 12910.160839545399, 13003.154794938298, 13023.153495022792, 13155.934220328838, 13431.395587198795, 12301.0, 12631.93684031574, 12889.355532223539, 13270.535531256359, 13382.330883455594, 11446.313221206754, 11461.828072578752, 11611.767764644828, 11914.642560723192, 11953.462094205608, 11990.220635658654, 12134.635960921187, 12198.14612977083, 12278.324692142018, 12769.616911492298, 11037.503312351124, 11065.391403472886, 11124.944375278072, 11215.214934954474, 11273.943630281796, 9296.07039296065, 10109.14072303833, 10121.342112762646, 10214.846777298495, 10632.574697012033], "unit": "ms", "name": "yui.html", "value": 6695.522053960873}], "name": "dromaeo_css", "value": 7407.206844539223}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 103.0, 108.0, 109.0, 109.0, 100.0, 109.0, 108.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.4}, {"lowerIsBetter": true, "replicates": [149.0, 124.0, 153.0, 159.0, 128.0, 148.0, 148.0, 123.0, 165.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [75.0, 71.0, 72.0, 155.0, 70.0, 71.0, 72.0, 73.0, 149.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.0}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 141.0, 91.0, 92.0, 92.0, 136.0, 92.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 105.6}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 144.0, 145.0, 147.0, 146.0, 145.0, 146.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 57.0, 56.0, 57.0, 57.0, 57.0, 58.0, 58.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.7}, {"lowerIsBetter": true, "replicates": [79.0, 78.0, 79.0, 80.0, 79.0, 80.0, 80.0, 80.0, 80.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.5}, {"lowerIsBetter": true, "replicates": [118.0, 116.0, 115.0, 115.0, 127.0, 109.0, 121.0, 114.0, 117.0, 127.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.9}, {"lowerIsBetter": true, "replicates": [135.0, 144.0, 146.0, 144.0, 144.0, 134.0, 146.0, 143.0, 141.0, 147.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.4}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.0}], "name": "kraken", "value": 1481.0000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "Richards", "value": 214.28313999999997}, {"lowerIsBetter": false, "replicates": [173.3102253032929], "unit": "score", "name": "DeltaBlue", "value": 381.5008599999999}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [956.0229445506692], "unit": "score", "name": "RayTrace", "value": 774.0284939999998}, {"lowerIsBetter": false, "replicates": [553.0973451327434], "unit": "score", "name": "Earley", "value": 1204.9651040000003}, {"lowerIsBetter": false, "replicates": [10945.652173913044], "unit": "score", "name": "Boyer", "value": 60.888377358490544}, {"lowerIsBetter": false, "replicates": [26315.78947368421], "unit": "score", "name": "RegExp", "value": 34.61742999999998}, {"lowerIsBetter": false, "replicates": [504.2864346949067], "unit": "score", "name": "Splay", "value": 161.59665299999998}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 22943.76922001816}]} 08:07:27 INFO - RETURN: dromaeo_css: 7407.21 08:07:27 INFO - RETURN: kraken: 1481.00 08:07:27 INFO - RETURN: v8_7: 22943.77 08:07:27 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "22943.77"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1481.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7407.21"}}} 08:07:27 INFO - Return code: 0 08:07:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:07:27 INFO - # TBPL SUCCESS # 08:07:27 INFO - Running post-action listener: _resource_record_post_action 08:07:27 INFO - Running post-run listener: _resource_record_post_run 08:07:28 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 0; Write bytes: 502132736; Read time: 0; Write time: 396740 08:07:28 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 237969408; Read time: 0; Write time: 228800 08:07:28 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 0; Write bytes: 264163328; Read time: 0; Write time: 167940 08:07:28 INFO - Running post-run listener: _upload_blobber_files 08:07:28 INFO - Blob upload gear active. 08:07:28 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:07:28 INFO - Copying logs to upload dir... 08:07:28 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1272.601568 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-01-21 08:07:28.637921) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-21 08:07:28.640956) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009973 build_url: 'https://queue.taskcluster.net/v1/task/iwA-FAeNRTmYVxvgr1rX8Q/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-21 08:07:28.692605) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 08:07:28.692949) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453390031.789058-1048474257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 08:07:28.742606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 08:07:28.742943) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 08:07:28.743259) ========= ========= Total master_lag: 0.30 =========