builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1453482086.17 results: success (0) buildid: 20160122063728 builduid: 7b04268e85d7456c9ccc5f589136c755 revision: 0ce166f4fec610dd1b3a99f348f19811c571b51b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.169540) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.170014) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.170481) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004917 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.191884) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.192164) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.380672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.380997) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.396489) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.396785) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.397129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.397418) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-22 09:01:26-- 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% 19.7M=0.001s 2016-01-22 09:01:26 (19.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110069 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.519117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.519416) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018054 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:26.569417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-22 09:01:26.569763) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0ce166f4fec610dd1b3a99f348f19811c571b51b --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 0ce166f4fec610dd1b3a99f348f19811c571b51b --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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-22 09:01:26,751 truncating revision to first 12 chars 2016-01-22 09:01:26,751 Setting DEBUG logging. 2016-01-22 09:01:26,751 attempt 1/10 2016-01-22 09:01:26,751 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0ce166f4fec6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-22 09:01:27,786 unpacking tar archive at: mozilla-aurora-0ce166f4fec6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.309492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-22 09:01:27.892108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:27.892456) ========= echo 0ce166f4fec610dd1b3a99f348f19811c571b51b in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'0ce166f4fec610dd1b3a99f348f19811c571b51b'] 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 0ce166f4fec610dd1b3a99f348f19811c571b51b program finished with exit code 0 elapsedTime=0.004659 script_repo_revision: '0ce166f4fec610dd1b3a99f348f19811c571b51b' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:27.941464) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:27.941784) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 09:01:27.951929) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-01-22 09:01:27.952232) ========= /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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:28 INFO - MultiFileLogger online at 20160122 09:01:28 in /builds/slave/test-pgo 09:01:28 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 09:01:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:01:28 INFO - {'append_to_log': False, 09:01:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:01:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:01:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:01:28 INFO - 'branch': 'Mozilla-Aurora', 09:01:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:28 INFO - 'config_files': ('talos/linux_config.py',), 09:01:28 INFO - 'default_actions': ('clobber', 09:01:28 INFO - 'read-buildbot-config', 09:01:28 INFO - 'download-and-extract', 09:01:28 INFO - 'populate-webroot', 09:01:28 INFO - 'create-virtualenv', 09:01:28 INFO - 'install', 09:01:28 INFO - 'run-tests'), 09:01:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:28 INFO - 'download_minidump_stackwalk': True, 09:01:28 INFO - 'download_symbols': 'ondemand', 09:01:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:28 INFO - 'installer_path': 'installer.exe', 09:01:28 INFO - 'log_level': 'info', 09:01:28 INFO - 'log_name': 'talos', 09:01:28 INFO - 'log_to_console': True, 09:01:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:28 INFO - 'opt_config_files': (), 09:01:28 INFO - 'pip_index': False, 09:01:28 INFO - 'sps_profile': False, 09:01:28 INFO - 'sps_profile_interval': 0, 09:01:28 INFO - 'suite': 'dromaeojs', 09:01:28 INFO - 'system_bits': '32', 09:01:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:28 INFO - 'title': 'talos-linux64-ix-004', 09:01:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:28 INFO - 'use_talos_json': True, 09:01:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:01:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:28 INFO - 'work_dir': 'build'} 09:01:28 INFO - ##### 09:01:28 INFO - ##### Running clobber step. 09:01:28 INFO - ##### 09:01:28 INFO - Running pre-action listener: _resource_record_pre_action 09:01:28 INFO - Running main action method: clobber 09:01:28 INFO - rmtree: /builds/slave/test-pgo/build 09:01:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:01:29 INFO - Running post-action listener: _resource_record_post_action 09:01:29 INFO - ##### 09:01:29 INFO - ##### Running read-buildbot-config step. 09:01:29 INFO - ##### 09:01:29 INFO - Running pre-action listener: _resource_record_pre_action 09:01:29 INFO - Running main action method: read_buildbot_config 09:01:29 INFO - Using buildbot properties: 09:01:29 INFO - { 09:01:29 INFO - "properties": { 09:01:29 INFO - "buildnumber": 209, 09:01:29 INFO - "product": "firefox", 09:01:29 INFO - "script_repo_revision": "production", 09:01:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:01:29 INFO - "repository": "", 09:01:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:01:29 INFO - "buildid": "20160122063728", 09:01:29 INFO - "slavename": "talos-linux64-ix-004", 09:01:29 INFO - "pgo_build": "True", 09:01:29 INFO - "basedir": "/builds/slave/test-pgo", 09:01:29 INFO - "project": "", 09:01:29 INFO - "platform": "ubuntu64_hw", 09:01:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:01:29 INFO - "slavebuilddir": "test", 09:01:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:01:29 INFO - "branch": "mozilla-aurora", 09:01:29 INFO - "repo_path": "releases/mozilla-aurora", 09:01:29 INFO - "stage_platform": "linux64-pgo", 09:01:29 INFO - "builduid": "7b04268e85d7456c9ccc5f589136c755", 09:01:29 INFO - "revision": "0ce166f4fec610dd1b3a99f348f19811c571b51b" 09:01:29 INFO - }, 09:01:29 INFO - "sourcestamp": { 09:01:29 INFO - "repository": "", 09:01:29 INFO - "hasPatch": false, 09:01:29 INFO - "project": "", 09:01:29 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:01:29 INFO - "changes": [ 09:01:29 INFO - { 09:01:29 INFO - "category": null, 09:01:29 INFO - "files": [ 09:01:29 INFO - { 09:01:29 INFO - "url": null, 09:01:29 INFO - "name": "https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 09:01:29 INFO - } 09:01:29 INFO - ], 09:01:29 INFO - "repository": "", 09:01:29 INFO - "rev": "0ce166f4fec610dd1b3a99f348f19811c571b51b", 09:01:29 INFO - "who": "mconley@mozilla.com", 09:01:29 INFO - "when": 1453481905, 09:01:29 INFO - "number": 7501312, 09:01:29 INFO - "comments": "Bug 1238180 - Avoid unsafe CPOWs when opening Page / Frame / Image Info from the context menu. r=florian, a=sledru", 09:01:29 INFO - "project": "", 09:01:29 INFO - "at": "Fri 22 Jan 2016 08:58:25", 09:01:29 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:01:29 INFO - "revlink": "", 09:01:29 INFO - "properties": [ 09:01:29 INFO - [ 09:01:29 INFO - "buildid", 09:01:29 INFO - "20160122063728", 09:01:29 INFO - "Change" 09:01:29 INFO - ], 09:01:29 INFO - [ 09:01:29 INFO - "builduid", 09:01:29 INFO - "7b04268e85d7456c9ccc5f589136c755", 09:01:29 INFO - "Change" 09:01:29 INFO - ], 09:01:29 INFO - [ 09:01:29 INFO - "pgo_build", 09:01:29 INFO - "True", 09:01:29 INFO - "Change" 09:01:29 INFO - ] 09:01:29 INFO - ], 09:01:29 INFO - "revision": "0ce166f4fec610dd1b3a99f348f19811c571b51b" 09:01:29 INFO - } 09:01:29 INFO - ], 09:01:29 INFO - "revision": "0ce166f4fec610dd1b3a99f348f19811c571b51b" 09:01:29 INFO - } 09:01:29 INFO - } 09:01:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 09:01:29 INFO - Running post-action listener: _resource_record_post_action 09:01:29 INFO - ##### 09:01:29 INFO - ##### Running download-and-extract step. 09:01:29 INFO - ##### 09:01:29 INFO - Running pre-action listener: _resource_record_pre_action 09:01:29 INFO - Running main action method: download_and_extract 09:01:29 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:29 INFO - https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/test_packages.json 09:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/test_packages.json 09:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:01:31 INFO - Downloaded 1302 bytes. 09:01:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:01:31 INFO - Using the following test package requirements: 09:01:31 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:01:31 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:01:31 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'jsshell-linux-x86_64.zip'], 09:01:31 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:01:31 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:01:31 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:01:31 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:01:31 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:01:31 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:01:31 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:31 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:01:31 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:31 INFO - https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:32 INFO - Downloaded 23159892 bytes. 09:01:32 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 09:01:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:01:32 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 09:01:33 INFO - Return code: 0 09:01:33 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 09:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:33 INFO - https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:01:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:01:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:01:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:36 INFO - Downloaded 23159892 bytes. 09:01:36 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 09:01:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:01:36 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 09:01:37 INFO - Return code: 0 09:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:37 INFO - https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:01:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:01:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:01:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:39 INFO - Downloaded 11132038 bytes. 09:01:39 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 09:01:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:01:39 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 09:01:39 INFO - Return code: 0 09:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:39 INFO - https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:01:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:01:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:01:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bUODrfdIQQynOXWD0dtxhg/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 09:01:40 INFO - Downloaded 58170318 bytes. 09:01:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:01:40 INFO - mkdir: /builds/slave/test-pgo/properties 09:01:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:01:40 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:01:40 INFO - Contents: 09:01:40 INFO - build_url:https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:01:40 INFO - Running post-action listener: _resource_record_post_action 09:01:40 INFO - Running post-action listener: set_extra_try_arguments 09:01:40 INFO - ##### 09:01:40 INFO - ##### Running populate-webroot step. 09:01:40 INFO - ##### 09:01:40 INFO - Running pre-action listener: _resource_record_pre_action 09:01:40 INFO - Running main action method: populate_webroot 09:01:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:40 INFO - '--tppagecycles', 09:01:40 INFO - '7'], 09:01:40 INFO - 'tests': ['tsvgm']}}, 09:01:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:01:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:01:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:01:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'tests': ['damp', 'tps']}, 09:01:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['damp', 'tps']}, 09:01:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:01:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:40 INFO - 'other': {'tests': ['a11yr', 09:01:40 INFO - 'ts_paint', 09:01:40 INFO - 'tpaint', 09:01:40 INFO - 'sessionrestore', 09:01:40 INFO - 'sessionrestore_no_auto_restore']}, 09:01:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['a11yr', 09:01:40 INFO - 'ts_paint', 09:01:40 INFO - 'tpaint', 09:01:40 INFO - 'sessionrestore', 09:01:40 INFO - 'sessionrestore_no_auto_restore']}, 09:01:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['a11yr', 09:01:40 INFO - 'ts_paint', 09:01:40 INFO - 'tpaint', 09:01:40 INFO - 'sessionrestore', 09:01:40 INFO - 'sessionrestore_no_auto_restore']}, 09:01:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['a11yr', 09:01:40 INFO - 'ts_paint', 09:01:40 INFO - 'tpaint', 09:01:40 INFO - 'sessionrestore', 09:01:40 INFO - 'sessionrestore_no_auto_restore']}, 09:01:40 INFO - 'other_l64': {'tests': ['a11yr', 09:01:40 INFO - 'ts_paint', 09:01:40 INFO - 'tpaint', 09:01:40 INFO - 'sessionrestore', 09:01:40 INFO - 'sessionrestore_no_auto_restore']}, 09:01:40 INFO - 'other_nol64': {'tests': ['a11yr', 09:01:40 INFO - 'ts_paint', 09:01:40 INFO - 'tpaint', 09:01:40 INFO - 'sessionrestore', 09:01:40 INFO - 'sessionrestore_no_auto_restore']}, 09:01:40 INFO - 'svgr': {'tests': ['tsvgx', 09:01:40 INFO - 'tsvgr_opacity', 09:01:40 INFO - 'tart', 09:01:40 INFO - 'tscrollx', 09:01:40 INFO - 'cart']}, 09:01:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['tsvgx', 09:01:40 INFO - 'tsvgr_opacity', 09:01:40 INFO - 'tart', 09:01:40 INFO - 'tscrollx', 09:01:40 INFO - 'cart']}, 09:01:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'tests': ['tp5o']}, 09:01:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'talos_options': ['--e10s'], 09:01:40 INFO - 'tests': ['tp5o']}, 09:01:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'talos_options': ['--xperf_path', 09:01:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:40 INFO - 'tests': ['tp5n']}, 09:01:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:40 INFO - 'talos_options': ['--e10s', 09:01:40 INFO - '--xperf_path', 09:01:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:40 INFO - 'tests': ['tp5n']}}, 09:01:40 INFO - 'talos.zip': {'path': '', 09:01:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:40 INFO - Running post-action listener: _resource_record_post_action 09:01:40 INFO - ##### 09:01:40 INFO - ##### Running create-virtualenv step. 09:01:40 INFO - ##### 09:01:40 INFO - Running pre-action listener: _resource_record_pre_action 09:01:40 INFO - Running main action method: create_virtualenv 09:01:40 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:01:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:01:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:01:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:41 INFO - Using real prefix '/usr' 09:01:41 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:01:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:43 INFO - Installing pip.................done. 09:01:43 INFO - Return code: 0 09:01:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:43 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:43 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 09:01:43 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 09:01:43 INFO - Using env: {'DISPLAY': ':0', 09:01:43 INFO - 'HOME': '/home/cltbld', 09:01:43 INFO - 'LANG': 'en_US.UTF-8', 09:01:43 INFO - 'LANGUAGE': 'en_US:en', 09:01:43 INFO - 'LOGNAME': 'cltbld', 09:01:43 INFO - 'MAIL': '/var/mail/cltbld', 09:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:43 INFO - 'MOZ_NO_REMOTE': '1', 09:01:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:43 INFO - 'NO_EM_RESTART': '1', 09:01:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:43 INFO - 'SHELL': '/bin/bash', 09:01:43 INFO - 'SHLVL': '1', 09:01:43 INFO - 'TERM': 'linux', 09:01:43 INFO - 'TMOUT': '86400', 09:01:43 INFO - 'USER': 'cltbld', 09:01:43 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:43 INFO - '_': '/tools/buildbot/bin/python'} 09:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:01:43 INFO - Cleaning up... 09:01:43 INFO - Return code: 0 09:01:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:43 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:43 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 09:01:43 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 09:01:43 INFO - Using env: {'DISPLAY': ':0', 09:01:43 INFO - 'HOME': '/home/cltbld', 09:01:43 INFO - 'LANG': 'en_US.UTF-8', 09:01:43 INFO - 'LANGUAGE': 'en_US:en', 09:01:43 INFO - 'LOGNAME': 'cltbld', 09:01:43 INFO - 'MAIL': '/var/mail/cltbld', 09:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:43 INFO - 'MOZ_NO_REMOTE': '1', 09:01:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:43 INFO - 'NO_EM_RESTART': '1', 09:01:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:43 INFO - 'SHELL': '/bin/bash', 09:01:43 INFO - 'SHLVL': '1', 09:01:43 INFO - 'TERM': 'linux', 09:01:43 INFO - 'TMOUT': '86400', 09:01:43 INFO - 'USER': 'cltbld', 09:01:43 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:43 INFO - '_': '/tools/buildbot/bin/python'} 09:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:43 INFO - Downloading/unpacking psutil>=0.7.1 09:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:45 INFO - Installing collected packages: psutil 09:01:45 INFO - Running setup.py install for psutil 09:01:45 INFO - building 'psutil._psutil_linux' extension 09:01:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:01:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:01:46 INFO - building 'psutil._psutil_posix' extension 09:01:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:01:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:01:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:46 INFO - Successfully installed psutil 09:01:46 INFO - Cleaning up... 09:01:46 INFO - Return code: 0 09:01:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:01: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')]} 09:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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', '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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:01: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 mozsystemmonitor==0.0.0 09:01:46 INFO - Using env: {'DISPLAY': ':0', 09:01:46 INFO - 'HOME': '/home/cltbld', 09:01:46 INFO - 'LANG': 'en_US.UTF-8', 09:01:46 INFO - 'LANGUAGE': 'en_US:en', 09:01:46 INFO - 'LOGNAME': 'cltbld', 09:01:46 INFO - 'MAIL': '/var/mail/cltbld', 09:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:46 INFO - 'MOZ_NO_REMOTE': '1', 09:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:46 INFO - 'NO_EM_RESTART': '1', 09:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:46 INFO - 'SHELL': '/bin/bash', 09:01:46 INFO - 'SHLVL': '1', 09:01:46 INFO - 'TERM': 'linux', 09:01:46 INFO - 'TMOUT': '86400', 09:01:46 INFO - 'USER': 'cltbld', 09:01:46 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:46 INFO - '_': '/tools/buildbot/bin/python'} 09:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:01:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:01:48 INFO - Installing collected packages: mozsystemmonitor 09:01:48 INFO - Running setup.py install for mozsystemmonitor 09:01:48 INFO - Successfully installed mozsystemmonitor 09:01:48 INFO - Cleaning up... 09:01:48 INFO - Return code: 0 09:01:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:48 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 09:01:48 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 09:01:48 INFO - Using env: {'DISPLAY': ':0', 09:01:48 INFO - 'HOME': '/home/cltbld', 09:01:48 INFO - 'LANG': 'en_US.UTF-8', 09:01:48 INFO - 'LANGUAGE': 'en_US:en', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MAIL': '/var/mail/cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SHLVL': '1', 09:01:48 INFO - 'TERM': 'linux', 09:01:48 INFO - 'TMOUT': '86400', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - '_': '/tools/buildbot/bin/python'} 09:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:48 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:50 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:01:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:51 INFO - Downloading docopt-0.6.1.tar.gz 09:01:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:51 INFO - Running setup.py install for blobuploader 09:01:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:51 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:01:51 INFO - Running setup.py install for requests 09:01:51 INFO - Running setup.py install for docopt 09:01:51 INFO - Successfully installed blobuploader requests docopt 09:01:51 INFO - Cleaning up... 09:01:51 INFO - Return code: 0 09:01:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:51 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:51 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 09:01:51 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 09:01:51 INFO - Using env: {'DISPLAY': ':0', 09:01:51 INFO - 'HOME': '/home/cltbld', 09:01:51 INFO - 'LANG': 'en_US.UTF-8', 09:01:51 INFO - 'LANGUAGE': 'en_US:en', 09:01:51 INFO - 'LOGNAME': 'cltbld', 09:01:51 INFO - 'MAIL': '/var/mail/cltbld', 09:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:51 INFO - 'MOZ_NO_REMOTE': '1', 09:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:51 INFO - 'NO_EM_RESTART': '1', 09:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:51 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:51 INFO - 'SHELL': '/bin/bash', 09:01:51 INFO - 'SHLVL': '1', 09:01:51 INFO - 'TERM': 'linux', 09:01:51 INFO - 'TMOUT': '86400', 09:01:51 INFO - 'USER': 'cltbld', 09:01:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:51 INFO - '_': '/tools/buildbot/bin/python'} 09:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:01:52 INFO - Running setup.py (path:/tmp/pip-oxtkwD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:01:52 INFO - Running setup.py (path:/tmp/pip-otxGoG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:01:52 INFO - Running setup.py (path:/tmp/pip-lpiG01-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:01:52 INFO - Running setup.py (path:/tmp/pip-XRox7_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:01:52 INFO - Running setup.py (path:/tmp/pip-J0869a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:01:52 INFO - Running setup.py (path:/tmp/pip-3WudrA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:01:52 INFO - Running setup.py (path:/tmp/pip-GHQ8ds-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:01:52 INFO - Running setup.py (path:/tmp/pip-qlHsJR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:01:52 INFO - Running setup.py (path:/tmp/pip-zok2Vh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:01:52 INFO - Running setup.py (path:/tmp/pip-s_UUE3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:01:52 INFO - Running setup.py (path:/tmp/pip-EO9Rot-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:01:52 INFO - Running setup.py (path:/tmp/pip-KnOX_l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:01:52 INFO - Running setup.py (path:/tmp/pip-MmATlz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:01:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:01:53 INFO - Running setup.py (path:/tmp/pip-NYcwrF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:01:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:01:53 INFO - Running setup.py (path:/tmp/pip-9lYr6y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:01:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:01:53 INFO - Running setup.py (path:/tmp/pip-deP94a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:01:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:01:53 INFO - Running setup.py (path:/tmp/pip-Qm9VKo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:01:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:53 INFO - Running setup.py install for manifestparser 09:01:53 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:01:53 INFO - Running setup.py install for mozcrash 09:01:53 INFO - Running setup.py install for mozdebug 09:01:53 INFO - Running setup.py install for mozdevice 09:01:53 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:01:53 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:01:53 INFO - Running setup.py install for mozfile 09:01:53 INFO - Running setup.py install for mozhttpd 09:01:53 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:01:53 INFO - Running setup.py install for mozinfo 09:01:54 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Running setup.py install for mozInstall 09:01:54 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Running setup.py install for mozleak 09:01:54 INFO - Running setup.py install for mozlog 09:01:54 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Running setup.py install for moznetwork 09:01:54 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Running setup.py install for mozprocess 09:01:54 INFO - Running setup.py install for mozprofile 09:01:54 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Running setup.py install for mozrunner 09:01:54 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:01:54 INFO - Running setup.py install for mozscreenshot 09:01:54 INFO - Running setup.py install for moztest 09:01:54 INFO - Running setup.py install for mozversion 09:01:55 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:01:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:55 INFO - Cleaning up... 09:01:55 INFO - Return code: 0 09:01:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:55 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:55 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 09:01:55 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 09:01:55 INFO - Using env: {'DISPLAY': ':0', 09:01:55 INFO - 'HOME': '/home/cltbld', 09:01:55 INFO - 'LANG': 'en_US.UTF-8', 09:01:55 INFO - 'LANGUAGE': 'en_US:en', 09:01:55 INFO - 'LOGNAME': 'cltbld', 09:01:55 INFO - 'MAIL': '/var/mail/cltbld', 09:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:55 INFO - 'MOZ_NO_REMOTE': '1', 09:01:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:55 INFO - 'NO_EM_RESTART': '1', 09:01:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:55 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:55 INFO - 'SHELL': '/bin/bash', 09:01:55 INFO - 'SHLVL': '1', 09:01:55 INFO - 'TERM': 'linux', 09:01:55 INFO - 'TMOUT': '86400', 09:01:55 INFO - 'USER': 'cltbld', 09:01:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:55 INFO - '_': '/tools/buildbot/bin/python'} 09:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:01:55 INFO - Running setup.py (path:/tmp/pip-eFomWK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:01:55 INFO - Running setup.py (path:/tmp/pip-BtDyxP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:01:55 INFO - Running setup.py (path:/tmp/pip-eML48V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:01:55 INFO - Running setup.py (path:/tmp/pip-G3VQkx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:01:55 INFO - Running setup.py (path:/tmp/pip-_7VyZT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:01:55 INFO - Running setup.py (path:/tmp/pip-ZBZJGR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:01:55 INFO - Running setup.py (path:/tmp/pip-rqc23Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:01:55 INFO - Running setup.py (path:/tmp/pip-fHaGZS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:01:55 INFO - Running setup.py (path:/tmp/pip-l8vdDQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:01:55 INFO - Running setup.py (path:/tmp/pip-9ZFP62-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:01:56 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)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:01:56 INFO - Running setup.py (path:/tmp/pip-OoEhn3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:01:56 INFO - Running setup.py (path:/tmp/pip-LK7zky-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:01:56 INFO - Running setup.py (path:/tmp/pip-PYAXHI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:01:56 INFO - Running setup.py (path:/tmp/pip-SkPKir-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:01:56 INFO - Running setup.py (path:/tmp/pip-KNuuFH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:01:56 INFO - Running setup.py (path:/tmp/pip-YjiOmZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:01:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:01:56 INFO - Running setup.py (path:/tmp/pip-HWnnFu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:58 INFO - Downloading blessings-1.6.tar.gz 09:01:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:58 INFO - Installing collected packages: blessings 09:01:58 INFO - Running setup.py install for blessings 09:01:58 INFO - Successfully installed blessings 09:01:58 INFO - Cleaning up... 09:01:58 INFO - Return code: 0 09:01:58 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:01:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:01:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:01:58 INFO - Reading from file tmpfile_stdout 09:01:58 INFO - Current package versions: 09:01:58 INFO - argparse == 1.2.1 09:01:58 INFO - blessings == 1.6 09:01:58 INFO - blobuploader == 1.2.4 09:01:58 INFO - docopt == 0.6.1 09:01:58 INFO - manifestparser == 1.1 09:01:58 INFO - mozInstall == 1.12 09:01:58 INFO - mozcrash == 0.16 09:01:58 INFO - mozdebug == 0.1 09:01:58 INFO - mozdevice == 0.47 09:01:58 INFO - mozfile == 1.2 09:01:58 INFO - mozhttpd == 0.7 09:01:58 INFO - mozinfo == 0.9 09:01:58 INFO - mozleak == 0.1 09:01:58 INFO - mozlog == 3.1 09:01:58 INFO - moznetwork == 0.27 09:01:58 INFO - mozprocess == 0.22 09:01:58 INFO - mozprofile == 0.27 09:01:58 INFO - mozrunner == 6.11 09:01:58 INFO - mozscreenshot == 0.1 09:01:58 INFO - mozsystemmonitor == 0.0 09:01:58 INFO - moztest == 0.7 09:01:58 INFO - mozversion == 1.4 09:01:58 INFO - psutil == 3.1.1 09:01:58 INFO - requests == 1.2.3 09:01:58 INFO - wsgiref == 0.1.2 09:01:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:58 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 09:01:58 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 09:01:58 INFO - Using env: {'DISPLAY': ':0', 09:01:58 INFO - 'HOME': '/home/cltbld', 09:01:58 INFO - 'LANG': 'en_US.UTF-8', 09:01:58 INFO - 'LANGUAGE': 'en_US:en', 09:01:58 INFO - 'LOGNAME': 'cltbld', 09:01:58 INFO - 'MAIL': '/var/mail/cltbld', 09:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:58 INFO - 'MOZ_NO_REMOTE': '1', 09:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:58 INFO - 'NO_EM_RESTART': '1', 09:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:58 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:58 INFO - 'SHELL': '/bin/bash', 09:01:58 INFO - 'SHLVL': '1', 09:01:58 INFO - 'TERM': 'linux', 09:01:58 INFO - 'TMOUT': '86400', 09:01:58 INFO - 'USER': 'cltbld', 09:01:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:58 INFO - '_': '/tools/buildbot/bin/python'} 09:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:01:59 INFO - Cleaning up... 09:01:59 INFO - Return code: 0 09:01:59 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:01: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')]} 09:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01: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', 'jsonschema'] in /builds/slave/test-pgo/build 09:01: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 jsonschema 09:01:59 INFO - Using env: {'DISPLAY': ':0', 09:01:59 INFO - 'HOME': '/home/cltbld', 09:01:59 INFO - 'LANG': 'en_US.UTF-8', 09:01:59 INFO - 'LANGUAGE': 'en_US:en', 09:01:59 INFO - 'LOGNAME': 'cltbld', 09:01:59 INFO - 'MAIL': '/var/mail/cltbld', 09:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:59 INFO - 'MOZ_NO_REMOTE': '1', 09:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:59 INFO - 'NO_EM_RESTART': '1', 09:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:59 INFO - 'SHELL': '/bin/bash', 09:01:59 INFO - 'SHLVL': '1', 09:01:59 INFO - 'TERM': 'linux', 09:01:59 INFO - 'TMOUT': '86400', 09:01:59 INFO - 'USER': 'cltbld', 09:01:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:59 INFO - '_': '/tools/buildbot/bin/python'} 09:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:59 INFO - Downloading/unpacking jsonschema 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:02:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:00 INFO - Downloading/unpacking functools32 (from jsonschema) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:01 INFO - warning: no files found matching '*.txt' 09:02:01 INFO - no previously-included directories found matching 'build' 09:02:01 INFO - no previously-included directories found matching 'dist' 09:02:01 INFO - no previously-included directories found matching '.git*' 09:02:01 INFO - Installing collected packages: jsonschema, functools32 09:02:01 INFO - Running setup.py install for functools32 09:02:01 INFO - warning: no files found matching '*.txt' 09:02:01 INFO - no previously-included directories found matching 'build' 09:02:01 INFO - no previously-included directories found matching 'dist' 09:02:01 INFO - no previously-included directories found matching '.git*' 09:02:01 INFO - Successfully installed jsonschema functools32 09:02:01 INFO - Cleaning up... 09:02:01 INFO - Return code: 0 09:02:01 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:01 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 0x7fb0ba958f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:01 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 09:02:01 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 09:02:01 INFO - Using env: {'DISPLAY': ':0', 09:02:01 INFO - 'HOME': '/home/cltbld', 09:02:01 INFO - 'LANG': 'en_US.UTF-8', 09:02:01 INFO - 'LANGUAGE': 'en_US:en', 09:02:01 INFO - 'LOGNAME': 'cltbld', 09:02:01 INFO - 'MAIL': '/var/mail/cltbld', 09:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:01 INFO - 'MOZ_NO_REMOTE': '1', 09:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:01 INFO - 'NO_EM_RESTART': '1', 09:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:01 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:01 INFO - 'SHELL': '/bin/bash', 09:02:01 INFO - 'SHLVL': '1', 09:02:01 INFO - 'TERM': 'linux', 09:02:01 INFO - 'TMOUT': '86400', 09:02:01 INFO - 'USER': 'cltbld', 09:02:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:01 INFO - '_': '/tools/buildbot/bin/python'} 09:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:01 INFO - Downloading/unpacking flake8 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:02:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:02:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:02:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:04 INFO - no previously-included directories found matching 'docs/_build' 09:02:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:02:04 INFO - Running setup.py install for pep8 09:02:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:04 INFO - no previously-included directories found matching 'docs/_build' 09:02:04 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:02:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:02:04 INFO - Cleaning up... 09:02:04 INFO - Return code: 0 09:02:04 INFO - Running post-action listener: _resource_record_post_action 09:02:04 INFO - Running post-action listener: _start_resource_monitoring 09:02:04 INFO - Starting resource monitoring. 09:02:04 INFO - ##### 09:02:04 INFO - ##### Running install step. 09:02:04 INFO - ##### 09:02:04 INFO - Running pre-action listener: _resource_record_pre_action 09:02:04 INFO - Running main action method: install 09:02:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:02:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:02:04 INFO - Reading from file tmpfile_stdout 09:02:04 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:02:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:02:04 INFO - Reading from file tmpfile_stdout 09:02:04 INFO - Output received: 09:02:04 INFO - Usage: mozinstall [options] installer 09:02:04 INFO - Options: 09:02:04 INFO - -h, --help show this help message and exit 09:02:04 INFO - -d DEST, --destination=DEST 09:02:04 INFO - Directory to install application into. [default: 09:02:04 INFO - "/builds/slave/test-pgo"] 09:02:04 INFO - --app=APP Application being installed. [default: firefox] 09:02:04 INFO - mkdir: /builds/slave/test-pgo/build/application 09:02:04 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'] 09:02:04 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 09:02:19 INFO - Reading from file tmpfile_stdout 09:02:19 INFO - Output received: 09:02:19 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:02:19 INFO - Running post-action listener: _resource_record_post_action 09:02:19 INFO - ##### 09:02:19 INFO - ##### Running run-tests step. 09:02:19 INFO - ##### 09:02:19 INFO - Running pre-action listener: _resource_record_pre_action 09:02:19 INFO - Running main action method: run_tests 09:02:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:02:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:02:19 INFO - Python 2.7.3 09:02:19 INFO - Return code: 0 09:02:19 INFO - grabbing minidump binary from tooltool 09:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:19 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 0x2044350>, '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 0x2045250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20456e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:02:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:02:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:02:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:19 INFO - Return code: 0 09:02:19 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:02:19 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:02:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:02:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:02:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:02:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:02:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:02:20 INFO - Return code: 0 09:02:20 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/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:02:20 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/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost 09:02:20 INFO - Using env: {'DISPLAY': ':0', 09:02:20 INFO - 'HOME': '/home/cltbld', 09:02:20 INFO - 'LANG': 'en_US.UTF-8', 09:02:20 INFO - 'LANGUAGE': 'en_US:en', 09:02:20 INFO - 'LOGNAME': 'cltbld', 09:02:20 INFO - 'MAIL': '/var/mail/cltbld', 09:02:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:02:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:20 INFO - 'MOZ_NO_REMOTE': '1', 09:02:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:20 INFO - 'NO_EM_RESTART': '1', 09:02:20 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:20 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:20 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:02:20 INFO - 'SHELL': '/bin/bash', 09:02:20 INFO - 'SHLVL': '1', 09:02:20 INFO - 'TERM': 'linux', 09:02:20 INFO - 'TMOUT': '86400', 09:02:20 INFO - 'USER': 'cltbld', 09:02:20 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387', 09:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:20 INFO - '_': '/tools/buildbot/bin/python'} 09:02:20 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/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] with output_timeout 3600 09:02:20 INFO - mozversion INFO | application_buildid: 20160122063728 09:02:20 INFO - mozversion INFO | application_changeset: 0ce166f4fec610dd1b3a99f348f19811c571b51b 09:02:20 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 09:02:20 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:20 INFO - mozversion INFO | application_name: Firefox 09:02:20 INFO - mozversion INFO | application_remotingname: firefox-dev 09:02:20 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:02:20 INFO - mozversion INFO | application_vendor: Mozilla 09:02:20 INFO - mozversion INFO | application_version: 45.0a2 09:02:20 INFO - mozversion INFO | platform_buildid: 20160122063728 09:02:20 INFO - mozversion INFO | platform_changeset: 0ce166f4fec610dd1b3a99f348f19811c571b51b 09:02:20 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:02:20 INFO - mozversion INFO | platform_version: 45.0a2 09:02:20 INFO - 2016-01-22 09:02:20,581 DEBUG : using testdate: 1453482140 09:02:20 INFO - 2016-01-22 09:02:20,582 DEBUG : actual date: 1453482140 09:02:20 INFO - 2016-01-22 09:02:20,590 INFO : starting webserver on 'localhost:47692' 09:02:20 INFO - 2016-01-22 09:02:20,591 INFO : Starting test suite talos-linux64-ix-004 09:02:20 INFO - 2016-01-22 09:02:20,591 INFO : Starting test dromaeo_css 09:02:20 INFO - 2016-01-22 09:02:20,591 DEBUG : operating with platform_type : linux_ 09:02:20 INFO - 2016-01-22 09:02:20,592 INFO : Initialising browser for dromaeo_css test... 09:02:20 INFO - 2016-01-22 09:02:20,608 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp73XwRi/profile http://localhost:47692/getInfo.html 09:02:24 INFO - 2016-01-22 09:02:24,574 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:02:24 INFO - 2016-01-22 09:02:24,574 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:02:24 INFO - 2016-01-22 09:02:24,574 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:02:24 INFO - 2016-01-22 09:02:24,575 DEBUG : BROWSER_OUTPUT: __metrics 09:02:24 INFO - 2016-01-22 09:02:24,772 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] 09:02:25 INFO - 2016-01-22 09:02:25,828 INFO : Browser initialized. 09:02:25 INFO - 2016-01-22 09:02:25,829 INFO : Running cycle 1/1 for dromaeo_css test... 09:02:25 INFO - 2016-01-22 09:02:25,829 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp73XwRi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:02:37 INFO - 2016-01-22 09:02:37,575 DEBUG : BROWSER_OUTPUT: RSS: Main: 138035200 09:02:37 INFO - 2016-01-22 09:02:37,575 DEBUG : BROWSER_OUTPUT: 09:03:28 INFO - 2016-01-22 09:03:28,405 DEBUG : BROWSER_OUTPUT: 1453482208404 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:05:36 INFO - 2016-01-22 09:05:36,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47692/tests/dromaeo/cssquery-ext.html) 09:05:37 INFO - 2016-01-22 09:05:37,813 DEBUG : BROWSER_OUTPUT: RSS: Main: 249184256 09:05:37 INFO - 2016-01-22 09:05:37,813 DEBUG : BROWSER_OUTPUT: 09:08:40 INFO - 2016-01-22 09:08:40,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/dromaeo/cssquery-ext.html (next: http://localhost:47692/tests/dromaeo/cssquery-jquery.html) 09:08:40 INFO - 2016-01-22 09:08:40,596 DEBUG : BROWSER_OUTPUT: RSS: Main: 215851008 09:08:40 INFO - 2016-01-22 09:08:40,597 DEBUG : BROWSER_OUTPUT: 09:11:42 INFO - 2016-01-22 09:11:42,430 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47692/tests/dromaeo/cssquery-mootools.html) 09:11:42 INFO - 2016-01-22 09:11:42,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 218443776 09:11:42 INFO - 2016-01-22 09:11:42,997 DEBUG : BROWSER_OUTPUT: 09:14:44 INFO - 2016-01-22 09:14:44,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47692/tests/dromaeo/cssquery-prototype.html) 09:14:45 INFO - 2016-01-22 09:14:45,311 DEBUG : BROWSER_OUTPUT: RSS: Main: 202551296 09:14:45 INFO - 2016-01-22 09:14:45,311 DEBUG : BROWSER_OUTPUT: 09:17:47 INFO - 2016-01-22 09:17:47,805 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47692/tests/dromaeo/cssquery-yui.html) 09:17:48 INFO - 2016-01-22 09:17:48,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 202182656 09:17:48 INFO - 2016-01-22 09:17:48,379 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,611 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/dromaeo/cssquery-yui.html (next: http://localhost:47692/tests/dromaeo/cssquery-dojo.html) 09:20:50 INFO - 2016-01-22 09:20:50,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 243904512 09:20:50 INFO - 2016-01-22 09:20:50,857 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,857 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:20:50 INFO - 2016-01-22 09:20:50,857 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:20:50 INFO - 2016-01-22 09:20:50,857 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:20:50 INFO - 2016-01-22 09:20:50,857 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:20:50 INFO - 2016-01-22 09:20:50,861 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3294.2917272786353;3337.699607035366;3369.747268954829;3370.0226934189072;3377.98311008445;5364.678119312844;5419.295491586093;5463.535599474045;5465.508104270615;5469.972650136744;5425.565954723623;5444.91832622511;5450.700211488366;5451.236826844245;5464.398916119224;4872.488388719186;4954.25686147079;4976.104301225778;5015.874603134915;5034.5468907798295;4963.255511673242;5060.873478163038;5120.820771273006;5186.170212765958;5194.091034069042;5135.743212839362;5136.8972620547565;5142;5145.614078944094;5171.0692075426405;4866.124097662419;4965.379327584052;5049.065922804293;5060.0132974070075;5123.487651234866;4820.204666230068;4887.804487820483;4892.975535122301;4910.116179087763;4924.064427758714;5328.040952628526;5396;5404.054290499147;5426.321709786277;5430.945690543083;5752;5804.912926306109;5834.5624078194305;5949.226600541945;5953.434423729782;5969.074793407029;5991.580589358747;5992.191054207713;5994.07091900756;6019.458248757589;5536.197251398565;5570.248016517799;5585.748641311151;5588.21764952911;5589.385167631557;5794.304683438015;5828.358880523139;5868.471837534557;5989.9101513477335;6049.879002419927;5813.215215945877;5816.331121920949;5821.214136091573;5847.122931560215;5847.532197424196;5899.734511946886;5902.940970590325;5903.291605007341;5904.7342869570975;5911.467967882912;5606.85982850432;5642.915356269659;5671.064274394761;5753.165790960287;5880.500157486576;5821.854453638693;5857.531397488191;5860.765569377262;5874.853128671817;5934.465898069195;4538.841140560065;4540.523245059288;4546.363509108727;4549.545045495424;4567.246404343313;3962.2471730371135;3997.360422332413;4044.5348784890116;4112.280350938574;4121.91756164875;5652.3217213934595;5652.491275785199;5653.123765816305;5659;5669.773209071591;6012.8797424052145;6032.909506357408;6034.155218269446;6036.667983260875;6039;5333.253344531769;5428.240046393508;5545.972270138624;5586.24585680936;5645.68948707825;5175.974120129451;5192.506711862367;5198.506141916512;5204.635675502678;5222.843314700565;5822.970885145547;6145.016797312409;6156.29202641702;6157.384261573807;6160.260768707783;5828.475437210672;5865.882682346414;5964.642121472725;5971.522278217645;6002.399760023963;5971.820845374646;5976.163337132718;5976.820695379146;5986.4013598639785;6028.09578563219;5614.747336369955;5764.510016648632;5773;5776.653400795965;5792.739326730223;5816.73824677882;5880.26496687914;5886;5891.558133139946;5920.467157955804;5740.540756739368;5850.180974663465;5860.912086318794;5903.527717782653;5909.793157239476;5538.363088244903;5583.050881350099;5686.857828554319;5854.707264636666;5868.09044598088;5780.219670344618;5785.334686510937;5786.045302525036;5794.971025144847;5796.797112101225;5851.1223316502865;5874.706264686663;5885.588008839341;5899.056151015817;5905.379935106752;5530.889382212414;5611.074172761612;5647.7176141193595;5732.713364331683;5796.884062318814 09:20:50 INFO - 2016-01-22 09:20:50,861 DEBUG : BROWSER_OUTPUT: |1;ext.html;630.5460068750498;651.6871901487289;660.4617236951884;667.2526770017581;669.6584741781692;30515.694843051562;31086.68913310869;31117.377652446936;41069.38395924064;42252.788736056355;45491.77254113733;45963.540364596345;46019.53980460195;46127.76936115323;46133.53866461334;9274.443533388001;9322.347435679505;9349.439033658002;9419.29355298352;9458.38520496169;8240.093589705128;8415.200555947205;10964.671059868177;11147.777044459106;11224.494897729623;11041.061509771678;11163.944180279088;11167.16246281528;11174.553017879316;11191.720206994809;8356.832863342699;8521.531315777629;10961.890381096207;11074.723131921686;11086.390248536327;11107.888921110847;11360.034397076259;11361.261518001304;11367.09063274936;11401;42650.786746066384;42658;42802.57197428017;43183;43265;3374.510695949098;3379.3241351729753;3395.915102122442;3538.398472259722;3801.8669346573015;32432.6756732432;32731.6726832731;32740.836295818368;32825.20985716217;32834.671653283636;4209.97895010523;4291.3777502262;4322.438083049184;4339.240632889232;4357.3899654048455;316887;317206;318102;318450;319176.9999999977;57467.13799293014;64376.678116610055;64545;64818.35181648217;64979;50422.747886261066;50676.74661626742;50710.746446267534;75129.24870751332;75226;17348;17423.73864392035;17547.21037553313;17562.64874702499;17577.91211043962;14044.789328160086;14076.85923140776;14112.294385280695;14114.576562703136;14124.081934674214;20456.795432045787;20959.266425675334;20966.58066838655;20975.37073887755;21007.789922100583;8322.8335433291;8489.61796719149;8496.787580310418;8559.786005349915;8699.217070463688;1019.8990080434181;1072.780080083581;1109.001898291528;1116.4864162485349;1192.5170306026073;30170;30204;30387.392252154834;30979.535306970414;31478;1056.4295280548417;1117.5237660054372;1175.1010476985114;1207.5169932027259;1264.633256355665;14155.575332740034;14874.851251487347;16293.918530407273;16474.659533315833;17267.65464690699;320367;374414;377069;377256;377279;48595.5140448591;55514;55698;55842;56000;1442.7691569349063;1445.4146070841325;1446.5298777897226;1456.2209218068283;1462.5027490653442;26480.86759566267;27725.30686732848;29114;29550.40899181961;30249.848750756104;50687.49312506828;51057;51223.74388128035;51261.48738512716;51275.23087153621;276885;278482;278497;279304;279368;34529.82735086309;35240;35386.64613353936;35429.29141417209;35549.289014220085;46140.53859461362;46516;46703.29945050895;46810;47003;38316.42525362066;39110.41334379929;40348.394774077824;40662.1867562653;40972;35192.64807351894;35324.293514130084;35450.29099417946;36109.45835812517;36588.63411365959;35623.93128206158;35869.82065089658;36259.637403625624;36325.81837090903;36351.63648363482;29088.41823163567;30600;31237.531437028007;31320.843395782875;31562.68437315689;24815.62776558317;24831.25506234816;26489.867550662122;26719.530425826768;28200.576991345555 09:20:50 INFO - 2016-01-22 09:20:50,862 DEBUG : BROWSER_OUTPUT: |2;jquery.html;374.58608237897124;380.89334986203863;392.5034830938863;393.9212157568487;395.34767633404897;15306.617334566641;16118.516444506655;17273.481795546122;18734.437966860998;18760.43718688438;11769.882301176985;12007.159498835084;12080;19463.610727785435;19808.108635111417;5110.667806592569;5157.871053223681;5194.428612852583;5195.870103247411;5200.583953283728;3919.2945269851416;3985.7409268397605;3994.1811928554575;4009.4587230723914;5198.480151984809;5463.098588732854;5623.9437605623825;5624.859378515529;5627.2684551008515;5633.971830140844;5385.488378604026;5480.945190548123;5493.945060549383;5508.283923090013;5571.052921003439;5287.101192797234;5309.230161626581;5316.521513063804;5400.621956463049;5605.131204663243;13092.803607945889;13143.934280328633;13317.400716967759;13337.733245335136;13585.116967397187;2247.5841969235744;2265.512914723326;2297.7357603875603;2322.0131444136173;2358.4575547624145;9373.671921482715;9623.759406014835;9667.613295468173;10041.297109202358;10437.373757574494;1907.1322548240655;1919.1555715485142;1952.9609407811906;1955.4231501706888;1992.4221975627052;57046.14430783541;58376.124358134664;58857;58881.70559147177;59018.40981590172;10742.462876856282;10816;10838.40388778609;10860.891391086147;10879.456027198608;13965.2543432071;14019.649508762159;14055.508057217949;14077.788833167511;14156.292185390896;8347.791305217297;8417.326613870875;8417.663293468193;8463;8483.830323393497;8304.377171712145;8308.252257296752;8372.49765014089;8444.957775211207;8452.28155606768;4566.931496027562;4609.193391156534;4653.046125544323;4690.671652984258;4699.765011749399;1684.4412980429008;1797.4338083503656;1864.0959134819602;1885.132838894124;1891.442024602718;441.1463817513122;446.2458445227555;939.3096074385321;1034.2044695965824;1126.718320419895;9949.950250248703;10742.462876856125;10878.347299162075;10944.726381840517;11051.17116216271;451.76282451713115;452.1206253836323;470.4025887123334;481.62673927705606;486.9123557759637;5823.980803359395;6024.518038556993;6199.070139479114;6209.099680546386;6227.159333490012;663767;664027;666695;706589;713257;77540.61229693929;79792.20207797848;81277;89549.55225223962;91953;541.5180489364469;548.9917651235155;552.5441510753644;553.7259056766926;553.7674176845735;10772.569097236023;11080.224384293344;11285.943570282425;11392.658220253406;11459.369734664673;37980.62019379771;38650.80674596609;39046.82859514219;39056.60943390644;39718.80140599279;49924.25113623371;50969.23546146885;51961.7401912988;52862.47137528729;53153.7342313286;10158.44128572906;10184.490775461049;10310.32982856112;10352.585896564053;10431.478426078515;12273.324967126893;12332.260064396165;12401.441935112762;12437.875621243671;13098.148620339647;15203.467878624195;15447.150406727289;15716.92141539285;15905.284262207997;15966.521004369888;15063.322150503034;15109.47116850905;15404.691906162037;15777.526674199793;15784;15709.135997520247;15742.370305188122;15813.604659883595;15969.760453593437;16027.2787724555;9794.902050979685;9920.751981200528;9991.700248992542;10168.949155254177;10171.898281017095;14096.295185240902;14951.925240374163;15502.224888755292;15679.29443175037;15874.365025398854 09:20:50 INFO - 2016-01-22 09:20:50,862 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1417.844037155912;1571.3164773323606;1572.7011867745127;1612.266418779455;1692.9238184281705;6788.830279243021;7511.361534269587;7808.063032436102;7817.335526480256;7818.413618978571;7803.531788092719;7943.245391687795;7949.165337639537;7967.043954725441;7967.20327967203;2040.2553068130173;2061.041615648723;2067.390119914621;2320.153144102403;2354.9411264718433;2064.0299059442036;2291.8510296830736;2322.628379459287;2322.7561106083785;2323.1984965187066;2265.716785401825;2288.6567014947723;2308.0998410619873;2325.720913490375;2329.196427232602;2282.589133955887;2297.517521320525;2305.1816605105114;2320.7447180810095;2322.1872344679314;2306.8615883047073;2314.6875171851752;2341.274204996457;2347.471818840764;2351.2240960483;7837.843243135106;7911.5253084815095;7920.326772224368;7924.643391047417;7927.920720792776;4794.18498855192;4830.758462076882;4871.391076115486;4887.706737595756;4904.705717656916;3113.937721245585;3158.3209609933974;3192.7924684895406;3221.774475786696;3222.081706713599;1008.6973907827696;1019.0166489337751;1032.8450732390127;1054.504382940017;1070.7644318249972;10973;10975.28660637056;10980.780384392267;10991.395473248967;11004.119670426348;4300.3549467580115;4336.783160842008;4392.341148827638;4401.867943961686;4402.84590039347;9412.294077944045;9423.811523769624;9429.387089839138;9439.24486041115;9442.669506567374;4894.975525122423;4918.778654960535;4921.803127874917;4922.384701912261;4923.433805112458;4472.552744725566;4501.122281155143;4502.009557897258;4505.234110201208;4508.909821803545;3410;3471.2016236265295;3753.1555400035318;3770.5098337215986;3842.8462861485227;1142.1319796954374;1210.0501106631252;1213.0841214882707;1219.939003049844;1219.951201951912;1132.8867113288604;1152.5274637398627;1154.255505199142;1347.6630842289428;1412.8869690424742;7139.500234983501;7152.284771522806;7237.457190710613;7254.056972593635;7257.129144502694;1126.718320419895;1134.2400591603646;1146.1976616368408;1156.410230782307;1162.3607016141088;5986.49114825236;6030.758769649165;6031.969840150771;6040.486558642477;6043.063325184604;9407.576659050359;9563.187129094105;9682.031796820262;9689.90310096904;9692.273079518924;9521.857172142423;9534.427934323963;9535.713928582154;9542.666006689733;9543.570539325747;7229.783106506708;7288;7288.12542494904;7291.671874765649;7583.886241706379;7240.855182896207;7266.309700578437;7313.926860731324;7314.634268286671;7323.304286092813;8489.235968762841;8825.999160285533;8850.070742571937;8918.19736223743;8946.463212207289;8654.567271636519;8772.473651580925;8801.031886492608;8815.955920220358;8861.955690221508;7122.18094919091;7134.750283740044;7182.928170718226;7185.928140718735;7188.38898693596;6200.937990620036;6297;7375.557466552024;7634;7718.459707820623;7228.855422891618;7310.81722956909;7318.597477138808;7320.26797320285;7418.109826820816;7049.259827718016;7054.858902822018;7116.715331386687;7129.180144283474;7159.821004474929;7249.166345870292;7289.599072051088;7291.270872912666;7297.1973082961895;7301.926980730124;6477.77327793525;6584.078229048033;6605.5376123670885;6609.272979972296;6617.404433600999;6246.250449946036;6247.969085100907;6249.1563638908165;6279.089532017741;6307.463865571478 09:20:50 INFO - 2016-01-22 09:20:50,863 DEBUG : BROWSER_OUTPUT: |4;prototype.html;339.7044571223036;354.59753180140535;358.6216541548665;358.72736720092723;362.1688225522427;5949.405059494048;6091.451769340759;6107.114468402085;6125.203723515942;6159.815205543835;5947.405259474051;6082.482988945934;6101.938980610193;6109.297430795449;6115.633062016283;4687.343771871923;4717.080169366975;4719.976400117995;4725.432948046239;4728.314394412808;4493.123840851035;4577.267637178053;4579.771011449431;4703.106409782147;4742.383490146276;4671.789769460365;4685;4686.976565117169;4687.929681054787;4704.717716936978;4688.648351373644;4689.577937985564;4695;4696.272077827942;4700.929486057695;4129.607687269705;4136.090060186754;4137.482814648169;4139.482564679415;4140.0270936329935;5710.543156547466;5812.738426770763;5815.360310365856;5823.854403639901;5824.679642619654;5448.346198456211;5456.781728730846;5458.536024437927;5465.098258787295;5469.0702580561;6152.292486364081;6434;6434.131392262031;6436.839079023015;6437.83905402364;4447.466304043503;4461.955380446187;4558.658100642432;4569.748663823489;4570.542945705402;6627.834304142339;6648.501362397839;6665.133532640774;6674.432673222782;6688.063671086052;4635.119327327864;4639.976800115978;4644.489106198315;4646.558576935186;4647.233206520954;4612.538746125428;4689.601383882408;4702;4756.500567440438;4767.95232047682;5423.484768946943;5433.918491222635;5442.401335853053;5462.617616766788;5463.890722185613;6470.38531339522;6523.151990241333;6531.608103513804;6531.738730450728;6533.673316334164;1664.6670665866634;1677.2620047179203;1681.445123109371;1682.8317168283074;1684.7304431290934;1068;1068.0441005300213;1087.3693257910393;1095.3592148593002;1166.6558365282262;6295;6332.176817013758;6334.841628959313;6336.778212762531;6340.239171299474;6657.400833924873;6660.467162626978;6660.733570657217;6669.699863506154;6672.2994085621285;6304.873902521924;6305.054241863757;6324.241091069009;6326.588771729823;6328.7468501260355;3563.8574457021946;3658.0854786303425;3658.176910195185;3658.341498530237;3675.5038069860225;6990.580565166107;7005.5796652200015;7008.719651213996;7012.193597736224;7012.439004879598;6853.485988550879;6963.059986901805;6974.337437943387;6976.790696279119;6981.371676549135;6839.487038472134;6846.110005699227;6861.4853885958755;6861.828454288683;6947.235804061448;6501.414872661483;6571.57284776488;6644.83387915306;6760.93239067603;6765.9661701691175;6637.834054148685;6644.60132392058;6659.800205993828;6687.899681504879;6700.865982680221;6943.236244013054;6946.687399067156;6953.582785032914;6955.095837541087;6963.9303606963285;4500.639948804022;4641.466231383434;4647.883802904955;4653.906921861476;4685.976570117127;6742.157230346207;6743.561668491532;6751.223609284995;6752.763653272112;6758.7296508139125;6543.1821022372205;6579.078928950046;6582.440492557995;6583.341665833378;6590.209174899042;6467.676616169267;6526;6565.868682626417;6567.671616419256;6570.178727659043;6441.774537891339;6442.806715798533;6452.419282264619;6452.9677351612945;6454.354564543508;6550.443212327005;6564.704588293443;6569.605823650596;6570.605763654196;6602.933970660232;6336.3030066693545;6435.839104022437;6483.157189565326;6498.220213574401;6501.252355978933 09:20:50 INFO - 2016-01-22 09:20:50,864 DEBUG : BROWSER_OUTPUT: |5;yui.html;682.2324884504932;715.2489885620101;746.6154930210939;766.2261116272563;800.5516910530101;13628.727425451498;13630.863691363083;13663.590092297247;13665.726685466298;13680.931595342021;12640.051996100283;14522.491712790055;14759.114453132792;14760.302565205006;14783.482578109768;770.2182284980721;781.9296263336298;917.6375331743957;931.483026920059;931.8695382646434;912.8356895758761;923.9907600924006;932.3007744191856;936.5691781780389;936.840737074699;964.6527250189926;970.2141265574871;974.0211087856694;974.0454354732367;974.0843607009389;797.6570074867805;811.0632219786156;931.5388882503137;964.4020707161535;968.2350942755215;713;714.2000958925984;714.4213187318289;714.9106361704787;715.7602203261913;13471.932640336834;13475.86524134756;13478.865211347858;13484.865151348458;13499.122557033761;12390.442430090667;12450.190737602026;12682.175658582162;12739.681507962283;12848.614541563767;10788.352698838095;11347.092232621371;11492.138089643311;11735.354555499443;11736.965683622127;3908.609139086097;3909.6285852843935;3957.2085582883537;3980.1641655252433;3986.7009974252046;47809.76095119537;48220.27669584924;53854.46145538534;54998.17502737422;55105.448945510034;10064.69805905824;10160.98390160978;10188.847167292497;10380.273380863267;10474.319169253975;18710.25158993652;18776.155073021746;18781.530461738566;18817.529561761065;18902.621947560972;7783.922160778433;7812.492188007761;7815.53106813593;7973.800654983555;8014.398920081017;4697.78859951303;4965.379327584052;4968.627352948543;4969.204927211629;4976.800927962841;3298.0600528849163;3329.883454079096;3334.666533346646;3344.414727422691;3347.514610381481;588.8498432899669;597.9521638268928;606.4905479397328;609.701246389275;618.0605479670875;666.9399754022161;667.89981502774;688.1913751342153;704.3519961635308;712.8431745016088;8378.371622128365;8523.318134549221;8557.786055348666;8828.470291782514;8889.066648001995;523.1054896127664;582.3215953414309;645.5384400153913;732.7031154855875;765.8200322924108;4236.724612900151;4444.28891377378;4462.598366147063;4468.50846406895;4598.793054312564;60505.69747151236;60694.69652651709;61197;61500.38499615036;61592;53751.73124134354;53816.19275710868;54229;54507.18239226415;54519.45480545223;47521.76239118852;48735.51264487381;49377.506224938006;49856.750716246184;49950;9264.490453025148;9347.485888276211;9361.672341468016;9407.670731524364;9660.468674222986;35165.64834351624;36147.819260903525;36239.81880090583;36560.26879462343;36741.08147296339;43022.13955720805;43080.35379469373;44845.775771122244;46682.76658616685;46923;11311.151663625425;11557.13321500874;11917.940410297893;11990.880091198977;12589.496420143092;12228.44971976281;12859.16415432994;13681.384337705027;13752.31238438054;13806.93096534511;12041.638750837488;12627.242365458102;12634;12850.485980560672;12981.545645902348;11199.21605487608;11467.254628449124;11682.47428865686;11812.114091443347;11844.644660660195;9919.256055795988;11920.523179073087;11959.461824217757;12236.510539578318;12813.743725125632;9245.676401325922;10484.423356715453;10708.946455267675;10777.730556736144;10957.287776294515;10443.530041148015;10568.260221784401;10903.127749780157;11157.776844463227;11301.547938082385 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,869 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: __startTimestamp1453483250856__endTimestamp 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5482.18 Median:5669.77 stddev:598.10 (10.5%) stddev-sans-first:574.79 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: Values: 3294.3 3337.7 3369.7 3370.0 3378.0 5364.7 5419.3 5463.5 5465.5 5470.0 5425.6 5444.9 5450.7 5451.2 5464.4 4872.5 4954.3 4976.1 5015.9 5034.5 4963.3 5060.9 5120.8 5186.2 5194.1 5135.7 5136.9 5142.0 5145.6 5171.1 4866.1 4965.4 5049.1 5060.0 5123.5 4820.2 4887.8 4893.0 4910.1 4924.1 5328.0 5396.0 5404.1 5426.3 5430.9 5752.0 5804.9 5834.6 5949.2 5953.4 5969.1 5991.6 5992.2 5994.1 6019.5 5536.2 5570.2 5585.7 5588.2 5589.4 5794.3 5828.4 5868.5 5989.9 6049.9 5813.2 5816.3 5821.2 5847.1 5847.5 5899.7 5902.9 5903.3 5904.7 5911.5 5606.9 5642.9 5671.1 5753.2 5880.5 5821.9 5857.5 5860.8 5874.9 5934.5 4538.8 4540.5 4546.4 4549.5 4567.2 3962.2 3997.4 4044.5 4112.3 4121.9 5652.3 5652.5 5653.1 5659.0 5669.8 6012.9 6032.9 6034.2 6036.7 6039.0 5333.3 5428.2 5546.0 5586.2 5645.7 5176.0 5192.5 5198.5 5204.6 5222.8 5823.0 6145.0 6156.3 6157.4 6160.3 5828.5 5865.9 5964.6 5971.5 6002.4 5971.8 5976.2 5976.8 5986.4 6028.1 5614.7 5764.5 5773.0 5776.7 5792.7 5816.7 5880.3 5886.0 5891.6 5920.5 5740.5 5850.2 5860.9 5903.5 5909.8 5538.4 5583.1 5686.9 5854.7 5868.1 5780.2 5785.3 5786.0 5795.0 5796.8 5851.1 5874.7 5885.6 5899.1 5905.4 5530.9 5611.1 5647.7 5732.7 5796.9 09:20:50 INFO - 2016-01-22 09:20:50,865 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49950.73 Median:3376.92 stddev:84442.33 (2500.6%) stddev-sans-first:84598.01 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: Values: 630.5 651.7 660.5 667.3 669.7 30515.7 31086.7 31117.4 41069.4 42252.8 45491.8 45963.5 46019.5 46127.8 46133.5 9274.4 9322.3 9349.4 9419.3 9458.4 8240.1 8415.2 10964.7 11147.8 11224.5 11041.1 11163.9 11167.2 11174.6 11191.7 8356.8 8521.5 10961.9 11074.7 11086.4 11107.9 11360.0 11361.3 11367.1 11401.0 42650.8 42658.0 42802.6 43183.0 43265.0 3374.5 3379.3 3395.9 3538.4 3801.9 32432.7 32731.7 32740.8 32825.2 32834.7 4210.0 4291.4 4322.4 4339.2 4357.4 316887.0 317206.0 318102.0 318450.0 319177.0 57467.1 64376.7 64545.0 64818.4 64979.0 50422.7 50676.7 50710.7 75129.2 75226.0 17348.0 17423.7 17547.2 17562.6 17577.9 14044.8 14076.9 14112.3 14114.6 14124.1 20456.8 20959.3 20966.6 20975.4 21007.8 8322.8 8489.6 8496.8 8559.8 8699.2 1019.9 1072.8 1109.0 1116.5 1192.5 30170.0 30204.0 30387.4 30979.5 31478.0 1056.4 1117.5 1175.1 1207.5 1264.6 14155.6 14874.9 16293.9 16474.7 17267.7 320367.0 374414.0 377069.0 377256.0 377279.0 48595.5 55514.0 55698.0 55842.0 56000.0 1442.8 1445.4 1446.5 1456.2 1462.5 26480.9 27725.3 29114.0 29550.4 30249.8 50687.5 51057.0 51223.7 51261.5 51275.2 276885.0 278482.0 278497.0 279304.0 279368.0 34529.8 35240.0 35386.6 35429.3 35549.3 46140.5 46516.0 46703.3 46810.0 47003.0 38316.4 39110.4 40348.4 40662.2 40972.0 35192.6 35324.3 35450.3 36109.5 36588.6 35623.9 35869.8 36259.6 36325.8 36351.6 29088.4 30600.0 31237.5 31320.8 31562.7 24815.6 24831.3 26489.9 26719.5 28200.6 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32633.39 Median:21487.95 stddev:111642.25 (519.6%) stddev-sans-first:111929.16 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: Values: 374.6 380.9 392.5 393.9 395.3 15306.6 16118.5 17273.5 18734.4 18760.4 11769.9 12007.2 12080.0 19463.6 19808.1 5110.7 5157.9 5194.4 5195.9 5200.6 3919.3 3985.7 3994.2 4009.5 5198.5 5463.1 5623.9 5624.9 5627.3 5634.0 5385.5 5480.9 5493.9 5508.3 5571.1 5287.1 5309.2 5316.5 5400.6 5605.1 13092.8 13143.9 13317.4 13337.7 13585.1 2247.6 2265.5 2297.7 2322.0 2358.5 9373.7 9623.8 9667.6 10041.3 10437.4 1907.1 1919.2 1953.0 1955.4 1992.4 57046.1 58376.1 58857.0 58881.7 59018.4 10742.5 10816.0 10838.4 10860.9 10879.5 13965.3 14019.6 14055.5 14077.8 14156.3 8347.8 8417.3 8417.7 8463.0 8483.8 8304.4 8308.3 8372.5 8445.0 8452.3 4566.9 4609.2 4653.0 4690.7 4699.8 1684.4 1797.4 1864.1 1885.1 1891.4 441.1 446.2 939.3 1034.2 1126.7 9950.0 10742.5 10878.3 10944.7 11051.2 451.8 452.1 470.4 481.6 486.9 5824.0 6024.5 6199.1 6209.1 6227.2 663767.0 664027.0 666695.0 706589.0 713257.0 77540.6 79792.2 81277.0 89549.6 91953.0 541.5 549.0 552.5 553.7 553.8 10772.6 11080.2 11285.9 11392.7 11459.4 37980.6 38650.8 39046.8 39056.6 39718.8 49924.3 50969.2 51961.7 52862.5 53153.7 10158.4 10184.5 10310.3 10352.6 10431.5 12273.3 12332.3 12401.4 12437.9 13098.1 15203.5 15447.2 15716.9 15905.3 15966.5 15063.3 15109.5 15404.7 15777.5 15784.0 15709.1 15742.4 15813.6 15969.8 16027.3 9794.9 9920.8 9991.7 10168.9 10171.9 14096.3 14951.9 15502.2 15679.3 15874.4 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,866 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5560.79 Median:6223.59 stddev:2882.24 (46.3%) stddev-sans-first:2873.50 09:20:50 INFO - 2016-01-22 09:20:50,867 DEBUG : BROWSER_OUTPUT: Values: 1417.8 1571.3 1572.7 1612.3 1692.9 6788.8 7511.4 7808.1 7817.3 7818.4 7803.5 7943.2 7949.2 7967.0 7967.2 2040.3 2061.0 2067.4 2320.2 2354.9 2064.0 2291.9 2322.6 2322.8 2323.2 2265.7 2288.7 2308.1 2325.7 2329.2 2282.6 2297.5 2305.2 2320.7 2322.2 2306.9 2314.7 2341.3 2347.5 2351.2 7837.8 7911.5 7920.3 7924.6 7927.9 4794.2 4830.8 4871.4 4887.7 4904.7 3113.9 3158.3 3192.8 3221.8 3222.1 1008.7 1019.0 1032.8 1054.5 1070.8 10973.0 10975.3 10980.8 10991.4 11004.1 4300.4 4336.8 4392.3 4401.9 4402.8 9412.3 9423.8 9429.4 9439.2 9442.7 4895.0 4918.8 4921.8 4922.4 4923.4 4472.6 4501.1 4502.0 4505.2 4508.9 3410.0 3471.2 3753.2 3770.5 3842.8 1142.1 1210.1 1213.1 1219.9 1220.0 1132.9 1152.5 1154.3 1347.7 1412.9 7139.5 7152.3 7237.5 7254.1 7257.1 1126.7 1134.2 1146.2 1156.4 1162.4 5986.5 6030.8 6032.0 6040.5 6043.1 9407.6 9563.2 9682.0 9689.9 9692.3 9521.9 9534.4 9535.7 9542.7 9543.6 7229.8 7288.0 7288.1 7291.7 7583.9 7240.9 7266.3 7313.9 7314.6 7323.3 8489.2 8826.0 8850.1 8918.2 8946.5 8654.6 8772.5 8801.0 8816.0 8862.0 7122.2 7134.8 7182.9 7185.9 7188.4 6200.9 6297.0 7375.6 7634.0 7718.5 7228.9 7310.8 7318.6 7320.3 7418.1 7049.3 7054.9 7116.7 7129.2 7159.8 7249.2 7289.6 7291.3 7297.2 7301.9 6477.8 6584.1 6605.5 6609.3 6617.4 6246.3 6248.0 6249.2 6279.1 6307.5 09:20:50 INFO - 2016-01-22 09:20:50,867 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,867 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5452.23 Median:6227.41 stddev:1637.30 (26.3%) stddev-sans-first:1596.29 09:20:50 INFO - 2016-01-22 09:20:50,869 DEBUG : BROWSER_OUTPUT: Values: 339.7 354.6 358.6 358.7 362.2 5949.4 6091.5 6107.1 6125.2 6159.8 5947.4 6082.5 6101.9 6109.3 6115.6 4687.3 4717.1 4720.0 4725.4 4728.3 4493.1 4577.3 4579.8 4703.1 4742.4 4671.8 4685.0 4687.0 4687.9 4704.7 4688.6 4689.6 4695.0 4696.3 4700.9 4129.6 4136.1 4137.5 4139.5 4140.0 5710.5 5812.7 5815.4 5823.9 5824.7 5448.3 5456.8 5458.5 5465.1 5469.1 6152.3 6434.0 6434.1 6436.8 6437.8 4447.5 4462.0 4558.7 4569.7 4570.5 6627.8 6648.5 6665.1 6674.4 6688.1 4635.1 4640.0 4644.5 4646.6 4647.2 4612.5 4689.6 4702.0 4756.5 4768.0 5423.5 5433.9 5442.4 5462.6 5463.9 6470.4 6523.2 6531.6 6531.7 6533.7 1664.7 1677.3 1681.4 1682.8 1684.7 1068.0 1068.0 1087.4 1095.4 1166.7 6295.0 6332.2 6334.8 6336.8 6340.2 6657.4 6660.5 6660.7 6669.7 6672.3 6304.9 6305.1 6324.2 6326.6 6328.7 3563.9 3658.1 3658.2 3658.3 3675.5 6990.6 7005.6 7008.7 7012.2 7012.4 6853.5 6963.1 6974.3 6976.8 6981.4 6839.5 6846.1 6861.5 6861.8 6947.2 6501.4 6571.6 6644.8 6760.9 6766.0 6637.8 6644.6 6659.8 6687.9 6700.9 6943.2 6946.7 6953.6 6955.1 6963.9 4500.6 4641.5 4647.9 4653.9 4686.0 6742.2 6743.6 6751.2 6752.8 6758.7 6543.2 6579.1 6582.4 6583.3 6590.2 6467.7 6526.0 6565.9 6567.7 6570.2 6441.8 6442.8 6452.4 6453.0 6454.4 6550.4 6564.7 6569.6 6570.6 6602.9 6336.3 6435.8 6483.2 6498.2 6501.3 09:20:50 INFO - 2016-01-22 09:20:50,869 DEBUG : BROWSER_OUTPUT: 09:20:50 INFO - 2016-01-22 09:20:50,869 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14556.13 Median:25640.78 stddev:16792.17 (65.5%) stddev-sans-first:16806.96 09:20:50 INFO - 2016-01-22 09:20:50,869 DEBUG : BROWSER_OUTPUT: Values: 682.2 715.2 746.6 766.2 800.6 13628.7 13630.9 13663.6 13665.7 13680.9 12640.1 14522.5 14759.1 14760.3 14783.5 770.2 781.9 917.6 931.5 931.9 912.8 924.0 932.3 936.6 936.8 964.7 970.2 974.0 974.0 974.1 797.7 811.1 931.5 964.4 968.2 713.0 714.2 714.4 714.9 715.8 13471.9 13475.9 13478.9 13484.9 13499.1 12390.4 12450.2 12682.2 12739.7 12848.6 10788.4 11347.1 11492.1 11735.4 11737.0 3908.6 3909.6 3957.2 3980.2 3986.7 47809.8 48220.3 53854.5 54998.2 55105.4 10064.7 10161.0 10188.8 10380.3 10474.3 18710.3 18776.2 18781.5 18817.5 18902.6 7783.9 7812.5 7815.5 7973.8 8014.4 4697.8 4965.4 4968.6 4969.2 4976.8 3298.1 3329.9 3334.7 3344.4 3347.5 588.8 598.0 606.5 609.7 618.1 666.9 667.9 688.2 704.4 712.8 8378.4 8523.3 8557.8 8828.5 8889.1 523.1 582.3 645.5 732.7 765.8 4236.7 4444.3 4462.6 4468.5 4598.8 60505.7 60694.7 61197.0 61500.4 61592.0 53751.7 53816.2 54229.0 54507.2 54519.5 47521.8 48735.5 49377.5 49856.8 49950.0 9264.5 9347.5 9361.7 9407.7 9660.5 35165.6 36147.8 36239.8 36560.3 36741.1 43022.1 43080.4 44845.8 46682.8 46923.0 11311.2 11557.1 11917.9 11990.9 12589.5 12228.4 12859.2 13681.4 13752.3 13806.9 12041.6 12627.2 12634.0 12850.5 12981.5 11199.2 11467.3 11682.5 11812.1 11844.6 9919.3 11920.5 11959.5 12236.5 12813.7 9245.7 10484.4 10708.9 10777.7 10957.3 10443.5 10568.3 10903.1 11157.8 11301.5 09:20:50 INFO - 2016-01-22 09:20:50,869 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:20:50 INFO - 2016-01-22 09:20:50,869 DEBUG : BROWSER_OUTPUT: 09:20:51 INFO - 2016-01-22 09:20:51,548 INFO : Browser exited with error code: 0 09:20:51 INFO - 2016-01-22 09:20:51,565 INFO : Completed test dromaeo_css (00:18:30) 09:20:51 INFO - 2016-01-22 09:20:51,565 INFO : Starting test kraken 09:20:51 INFO - 2016-01-22 09:20:51,565 DEBUG : operating with platform_type : linux_ 09:20:51 INFO - 2016-01-22 09:20:51,565 INFO : Initialising browser for kraken test... 09:20:51 INFO - 2016-01-22 09:20:51,571 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMfgop5/profile http://localhost:47692/getInfo.html 09:20:55 INFO - 2016-01-22 09:20:55,804 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:20:55 INFO - 2016-01-22 09:20:55,804 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:20:55 INFO - 2016-01-22 09:20:55,804 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:20:55 INFO - 2016-01-22 09:20:55,804 DEBUG : BROWSER_OUTPUT: __metrics 09:20:55 INFO - 2016-01-22 09:20:55,969 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] 09:20:56 INFO - 2016-01-22 09:20:56,790 INFO : Browser initialized. 09:20:56 INFO - 2016-01-22 09:20:56,791 INFO : Running cycle 1/1 for kraken test... 09:20:56 INFO - 2016-01-22 09:20:56,791 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMfgop5/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:21:08 INFO - 2016-01-22 09:21:08,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 136572928 09:21:08 INFO - 2016-01-22 09:21:08,569 DEBUG : BROWSER_OUTPUT: 09:21:11 INFO - 2016-01-22 09:21:11,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47692/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:11 INFO - 2016-01-22 09:21:11,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 169869312 09:21:11 INFO - 2016-01-22 09:21:11,568 DEBUG : BROWSER_OUTPUT: 09:21:14 INFO - 2016-01-22 09:21:14,104 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47692/tests/kraken/driver.html?testName=audio-dft) 09:21:14 INFO - 2016-01-22 09:21:14,674 DEBUG : BROWSER_OUTPUT: RSS: Main: 167596032 09:21:14 INFO - 2016-01-22 09:21:14,674 DEBUG : BROWSER_OUTPUT: 09:21:17 INFO - 2016-01-22 09:21:17,478 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47692/tests/kraken/driver.html?testName=audio-fft) 09:21:18 INFO - 2016-01-22 09:21:18,039 DEBUG : BROWSER_OUTPUT: RSS: Main: 162758656 09:21:18 INFO - 2016-01-22 09:21:18,039 DEBUG : BROWSER_OUTPUT: 09:21:20 INFO - 2016-01-22 09:21:20,037 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47692/tests/kraken/driver.html?testName=audio-oscillator) 09:21:20 INFO - 2016-01-22 09:21:20,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 163602432 09:21:20 INFO - 2016-01-22 09:21:20,603 DEBUG : BROWSER_OUTPUT: 09:21:22 INFO - 2016-01-22 09:21:22,777 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47692/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:23 INFO - 2016-01-22 09:21:23,339 DEBUG : BROWSER_OUTPUT: RSS: Main: 163037184 09:21:23 INFO - 2016-01-22 09:21:23,339 DEBUG : BROWSER_OUTPUT: 09:21:26 INFO - 2016-01-22 09:21:26,222 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47692/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:26 INFO - 2016-01-22 09:21:26,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 164470784 09:21:26 INFO - 2016-01-22 09:21:26,789 DEBUG : BROWSER_OUTPUT: 09:21:30 INFO - 2016-01-22 09:21:30,133 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47692/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:30 INFO - 2016-01-22 09:21:30,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 163057664 09:21:30 INFO - 2016-01-22 09:21:30,699 DEBUG : BROWSER_OUTPUT: 09:21:33 INFO - 2016-01-22 09:21:33,388 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47692/tests/kraken/driver.html?testName=json-parse-financial) 09:21:33 INFO - 2016-01-22 09:21:33,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 165318656 09:21:33 INFO - 2016-01-22 09:21:33,971 DEBUG : BROWSER_OUTPUT: 09:21:35 INFO - 2016-01-22 09:21:35,507 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47692/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:36 INFO - 2016-01-22 09:21:36,068 DEBUG : BROWSER_OUTPUT: RSS: Main: 170479616 09:21:36 INFO - 2016-01-22 09:21:36,069 DEBUG : BROWSER_OUTPUT: 09:21:37 INFO - 2016-01-22 09:21:37,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:38 INFO - 2016-01-22 09:21:38,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 166932480 09:21:38 INFO - 2016-01-22 09:21:38,429 DEBUG : BROWSER_OUTPUT: 09:21:40 INFO - 2016-01-22 09:21:40,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:41 INFO - 2016-01-22 09:21:41,020 DEBUG : BROWSER_OUTPUT: RSS: Main: 177389568 09:21:41 INFO - 2016-01-22 09:21:41,020 DEBUG : BROWSER_OUTPUT: 09:21:43 INFO - 2016-01-22 09:21:43,420 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:43 INFO - 2016-01-22 09:21:43,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 182943744 09:21:43 INFO - 2016-01-22 09:21:43,994 DEBUG : BROWSER_OUTPUT: 09:21:46 INFO - 2016-01-22 09:21:46,495 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:47 INFO - 2016-01-22 09:21:47,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 183205888 09:21:47 INFO - 2016-01-22 09:21:47,079 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47692/tests/kraken/driver.html?testName=ai-astar) 09:21:48 INFO - 2016-01-22 09:21:48,977 DEBUG : BROWSER_OUTPUT: RSS: Main: 239984640 09:21:48 INFO - 2016-01-22 09:21:48,978 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,981 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:21:48 INFO - 2016-01-22 09:21:48,981 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:21:48 INFO - 2016-01-22 09:21:48,982 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:21:48 INFO - 2016-01-22 09:21:48,982 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:21:48 INFO - 2016-01-22 09:21:48,982 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;103;110;111;109;100;108;108;108 09:21:48 INFO - 2016-01-22 09:21:48,982 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;122;144;163;122;145;148;123;157;124 09:21:48 INFO - 2016-01-22 09:21:48,982 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;160;160;162;159;159;159;161;159 09:21:48 INFO - 2016-01-22 09:21:48,983 DEBUG : BROWSER_OUTPUT: |3;audio-fft;77;71;75;150;71;71;71;72;151;74 09:21:48 INFO - 2016-01-22 09:21:48,983 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;91;139;92;93;93;139;92;93;143 09:21:48 INFO - 2016-01-22 09:21:48,983 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;108;109;108;109;109;109 09:21:48 INFO - 2016-01-22 09:21:48,983 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;147;147;146;145;146;147;145 09:21:48 INFO - 2016-01-22 09:21:48,984 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;95;96;95;95;95 09:21:48 INFO - 2016-01-22 09:21:48,984 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;57;57;56;58;56;58;58;55;57 09:21:48 INFO - 2016-01-22 09:21:48,984 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;64;64;64;64;64;64;64 09:21:48 INFO - 2016-01-22 09:21:48,984 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;79;81;83;88;80;81;82;80;80 09:21:48 INFO - 2016-01-22 09:21:48,985 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;112;113;114;126;111;137;119;119;119 09:21:48 INFO - 2016-01-22 09:21:48,985 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;157;142;144;154;139;137;143;141;143 09:21:48 INFO - 2016-01-22 09:21:48,985 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;65;66;66;66;64;65;64;63 09:21:48 INFO - 2016-01-22 09:21:48,985 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:21:48 INFO - 2016-01-22 09:21:48,985 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:21:48 INFO - 2016-01-22 09:21:48,986 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,907 09:21:48 INFO - 2016-01-22 09:21:48,986 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:21:48 INFO - 2016-01-22 09:21:48,986 DEBUG : BROWSER_OUTPUT: __startTimestamp1453483308979__endTimestamp 09:21:48 INFO - 2016-01-22 09:21:48,986 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.40 Median:108.50 stddev:3.41 (3.1%) stddev-sans-first:3.61 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 103.0 110.0 111.0 109.0 100.0 108.0 108.0 108.0 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.80 Median:146.50 stddev:15.69 (10.7%) stddev-sans-first:16.20 09:21:48 INFO - 2016-01-22 09:21:48,990 DEBUG : BROWSER_OUTPUT: Values: 150.0 122.0 144.0 163.0 122.0 145.0 148.0 123.0 157.0 124.0 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 160.0 160.0 162.0 159.0 159.0 159.0 161.0 159.0 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.30 Median:71.50 stddev:32.85 (45.9%) stddev-sans-first:34.58 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: Values: 77.0 71.0 75.0 150.0 71.0 71.0 71.0 72.0 151.0 74.0 09:21:48 INFO - 2016-01-22 09:21:48,991 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:92.50 stddev:23.17 (25.1%) stddev-sans-first:24.04 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: Values: 93.0 91.0 139.0 92.0 93.0 93.0 139.0 92.0 93.0 143.0 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 108.0 109.0 108.0 109.0 109.0 109.0 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,992 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 147.0 147.0 146.0 145.0 146.0 147.0 145.0 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:1.03 (1.8%) stddev-sans-first:1.05 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: Values: 56.0 57.0 57.0 56.0 58.0 56.0 58.0 58.0 55.0 57.0 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,993 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.00 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:2.78 (3.4%) stddev-sans-first:2.70 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: Values: 78.0 79.0 81.0 83.0 88.0 80.0 81.0 82.0 80.0 80.0 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.40 Median:119.00 stddev:7.96 (6.7%) stddev-sans-first:8.27 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: Values: 124.0 112.0 113.0 114.0 126.0 111.0 137.0 119.0 119.0 119.0 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.50 Median:143.00 stddev:6.96 (4.9%) stddev-sans-first:6.67 09:21:48 INFO - 2016-01-22 09:21:48,994 DEBUG : BROWSER_OUTPUT: Values: 135.0 157.0 142.0 144.0 154.0 139.0 137.0 143.0 141.0 143.0 09:21:48 INFO - 2016-01-22 09:21:48,995 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-01-22 09:21:48,995 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.12 09:21:48 INFO - 2016-01-22 09:21:48,995 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 65.0 66.0 66.0 66.0 64.0 65.0 64.0 63.0 09:21:48 INFO - 2016-01-22 09:21:48,995 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:21:48 INFO - 2016-01-22 09:21:48,995 DEBUG : BROWSER_OUTPUT: 09:21:49 INFO - 2016-01-22 09:21:49,800 INFO : Browser exited with error code: 0 09:21:49 INFO - 2016-01-22 09:21:49,854 INFO : Completed test kraken (00:00:58) 09:21:49 INFO - 2016-01-22 09:21:49,854 INFO : Starting test v8_7 09:21:49 INFO - 2016-01-22 09:21:49,854 DEBUG : operating with platform_type : linux_ 09:21:49 INFO - 2016-01-22 09:21:49,854 INFO : Initialising browser for v8_7 test... 09:21:49 INFO - 2016-01-22 09:21:49,860 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoNVWLm/profile http://localhost:47692/getInfo.html 09:21:53 INFO - 2016-01-22 09:21:53,648 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:21:53 INFO - 2016-01-22 09:21:53,648 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:21:53 INFO - 2016-01-22 09:21:53,649 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:21:53 INFO - 2016-01-22 09:21:53,649 DEBUG : BROWSER_OUTPUT: __metrics 09:21:53 INFO - 2016-01-22 09:21:53,790 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] 09:21:54 INFO - 2016-01-22 09:21:54,865 INFO : Browser initialized. 09:21:54 INFO - 2016-01-22 09:21:54,866 INFO : Running cycle 1/1 for v8_7 test... 09:21:54 INFO - 2016-01-22 09:21:54,866 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoNVWLm/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:22:06 INFO - 2016-01-22 09:22:06,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 137981952 09:22:06 INFO - 2016-01-22 09:22:06,640 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,001 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47692/tests/v8_7/run.html (next: http://localhost:47692/tests/v8_7/run.html) 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: RSS: Main: 426741760 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: |0;Richards;164.82610845557937 09:22:28 INFO - 2016-01-22 09:22:28,252 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.53224995811695 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.72027972027973 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5128.205128205128 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |4;RayTrace;984.2519685039371 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |5;Earley;558.0357142857143 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |6;Boyer;11087.912087912087 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |7;RegExp;24261.904761904763 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |8;Splay;529.6610169491526 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: __startTimestamp1453483348249__endTimestamp 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:22:28 INFO - 2016-01-22 09:22:28,253 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.83 Median:164.83 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: Values: 164.8 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.53 Median:167.53 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: Values: 167.5 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.72 Median:279.72 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: Values: 279.7 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5128.21 Median:5128.21 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: Values: 5128.2 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:984.25 Median:984.25 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: Values: 984.3 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,254 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:558.04 Median:558.04 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: Values: 558.0 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11087.91 Median:11087.91 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: Values: 11087.9 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24261.90 Median:24261.90 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: Values: 24261.9 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:529.66 Median:529.66 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: Values: 529.7 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: Values: 4835.7 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:22:28 INFO - 2016-01-22 09:22:28,255 DEBUG : BROWSER_OUTPUT: 09:22:29 INFO - 2016-01-22 09:22:29,076 INFO : Browser exited with error code: 0 09:22:29 INFO - 2016-01-22 09:22:29,093 INFO : Completed test v8_7 (00:00:39) 09:22:29 INFO - 2016-01-22 09:22:29,309 INFO : Completed test suite (00:20:08) 09:22:29 INFO - 2016-01-22 09:22:29,309 DEBUG : Working with test: dromaeo_css 09:22:29 INFO - 2016-01-22 09:22:29,309 DEBUG : Generating results file: dromaeo_css 09:22:29 INFO - 2016-01-22 09:22:29,310 DEBUG : Working with test: kraken 09:22:29 INFO - 2016-01-22 09:22:29,310 DEBUG : Generating results file: kraken 09:22:29 INFO - 2016-01-22 09:22:29,311 INFO : javascript benchmark 09:22:29 INFO - 2016-01-22 09:22:29,311 DEBUG : Working with test: v8_7 09:22:29 INFO - 2016-01-22 09:22:29,311 DEBUG : Generating results file: v8_7 09:22:29 INFO - 2016-01-22 09:22:29,311 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:29 INFO - 2016-01-22 09:22:29,369 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 09:22:29 INFO - 2016-01-22 09:22:29,369 DEBUG : process_Request line: dromaeo_css 7182.85 graph.html#tests=[[72,52,35]] 09:22:29 INFO - 2016-01-22 09:22:29,369 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:29 INFO - 2016-01-22 09:22:29,400 DEBUG : process_Request line: kraken 1482.50 graph.html#tests=[[232,52,35]] 09:22:29 INFO - 2016-01-22 09:22:29,400 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:29 INFO - 2016-01-22 09:22:29,428 DEBUG : process_Request line: v8_7 23005.22 graph.html#tests=[[230,52,35]] 09:22:29 INFO - 2016-01-22 09:22:29,430 INFO : javascript benchmark 09:22:29 INFO - 2016-01-22 09:22:29,433 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3294.2917272786353, 3337.699607035366, 3369.747268954829, 3370.0226934189072, 3377.98311008445, 5364.678119312844, 5419.295491586093, 5463.535599474045, 5465.508104270615, 5469.972650136744, 5425.565954723623, 5444.91832622511, 5450.700211488366, 5451.236826844245, 5464.398916119224, 4872.488388719186, 4954.25686147079, 4976.104301225778, 5015.874603134915, 5034.5468907798295, 4963.255511673242, 5060.873478163038, 5120.820771273006, 5186.170212765958, 5194.091034069042, 5135.743212839362, 5136.8972620547565, 5142.0, 5145.614078944094, 5171.0692075426405, 4866.124097662419, 4965.379327584052, 5049.065922804293, 5060.0132974070075, 5123.487651234866, 4820.204666230068, 4887.804487820483, 4892.975535122301, 4910.116179087763, 4924.064427758714, 5328.040952628526, 5396.0, 5404.054290499147, 5426.321709786277, 5430.945690543083, 5752.0, 5804.912926306109, 5834.5624078194305, 5949.226600541945, 5953.434423729782, 5969.074793407029, 5991.580589358747, 5992.191054207713, 5994.07091900756, 6019.458248757589, 5536.197251398565, 5570.248016517799, 5585.748641311151, 5588.21764952911, 5589.385167631557, 5794.304683438015, 5828.358880523139, 5868.471837534557, 5989.9101513477335, 6049.879002419927, 5813.215215945877, 5816.331121920949, 5821.214136091573, 5847.122931560215, 5847.532197424196, 5899.734511946886, 5902.940970590325, 5903.291605007341, 5904.7342869570975, 5911.467967882912, 5606.85982850432, 5642.915356269659, 5671.064274394761, 5753.165790960287, 5880.500157486576, 5821.854453638693, 5857.531397488191, 5860.765569377262, 5874.853128671817, 5934.465898069195, 4538.841140560065, 4540.523245059288, 4546.363509108727, 4549.545045495424, 4567.246404343313, 3962.2471730371135, 3997.360422332413, 4044.5348784890116, 4112.280350938574, 4121.91756164875, 5652.3217213934595, 5652.491275785199, 5653.123765816305, 5659.0, 5669.773209071591, 6012.8797424052145, 6032.909506357408, 6034.155218269446, 6036.667983260875, 6039.0, 5333.253344531769, 5428.240046393508, 5545.972270138624, 5586.24585680936, 5645.68948707825, 5175.974120129451, 5192.506711862367, 5198.506141916512, 5204.635675502678, 5222.843314700565, 5822.970885145547, 6145.016797312409, 6156.29202641702, 6157.384261573807, 6160.260768707783, 5828.475437210672, 5865.882682346414, 5964.642121472725, 5971.522278217645, 6002.399760023963, 5971.820845374646, 5976.163337132718, 5976.820695379146, 5986.4013598639785, 6028.09578563219, 5614.747336369955, 5764.510016648632, 5773.0, 5776.653400795965, 5792.739326730223, 5816.73824677882, 5880.26496687914, 5886.0, 5891.558133139946, 5920.467157955804, 5740.540756739368, 5850.180974663465, 5860.912086318794, 5903.527717782653, 5909.793157239476, 5538.363088244903, 5583.050881350099, 5686.857828554319, 5854.707264636666, 5868.09044598088, 5780.219670344618, 5785.334686510937, 5786.045302525036, 5794.971025144847, 5796.797112101225, 5851.1223316502865, 5874.706264686663, 5885.588008839341, 5899.056151015817, 5905.379935106752, 5530.889382212414, 5611.074172761612, 5647.7176141193595, 5732.713364331683, 5796.884062318814], "unit": "ms", "name": "dojo.html", "value": 5444.248765877419}, {"lowerIsBetter": false, "replicates": [630.5460068750498, 651.6871901487289, 660.4617236951884, 667.2526770017581, 669.6584741781692, 30515.694843051562, 31086.68913310869, 31117.377652446936, 41069.38395924064, 42252.788736056355, 45491.77254113733, 45963.540364596345, 46019.53980460195, 46127.76936115323, 46133.53866461334, 9274.443533388001, 9322.347435679505, 9349.439033658002, 9419.29355298352, 9458.38520496169, 8240.093589705128, 8415.200555947205, 10964.671059868177, 11147.777044459106, 11224.494897729623, 11041.061509771678, 11163.944180279088, 11167.16246281528, 11174.553017879316, 11191.720206994809, 8356.832863342699, 8521.531315777629, 10961.890381096207, 11074.723131921686, 11086.390248536327, 11107.888921110847, 11360.034397076259, 11361.261518001304, 11367.09063274936, 11401.0, 42650.786746066384, 42658.0, 42802.57197428017, 43183.0, 43265.0, 3374.510695949098, 3379.3241351729753, 3395.915102122442, 3538.398472259722, 3801.8669346573015, 32432.6756732432, 32731.6726832731, 32740.836295818368, 32825.20985716217, 32834.671653283636, 4209.97895010523, 4291.3777502262, 4322.438083049184, 4339.240632889232, 4357.3899654048455, 316887.0, 317206.0, 318102.0, 318450.0, 319176.9999999977, 57467.13799293014, 64376.678116610055, 64545.0, 64818.35181648217, 64979.0, 50422.747886261066, 50676.74661626742, 50710.746446267534, 75129.24870751332, 75226.0, 17348.0, 17423.73864392035, 17547.21037553313, 17562.64874702499, 17577.91211043962, 14044.789328160086, 14076.85923140776, 14112.294385280695, 14114.576562703136, 14124.081934674214, 20456.795432045787, 20959.266425675334, 20966.58066838655, 20975.37073887755, 21007.789922100583, 8322.8335433291, 8489.61796719149, 8496.787580310418, 8559.786005349915, 8699.217070463688, 1019.8990080434181, 1072.780080083581, 1109.001898291528, 1116.4864162485349, 1192.5170306026073, 30170.0, 30204.0, 30387.392252154834, 30979.535306970414, 31478.0, 1056.4295280548417, 1117.5237660054372, 1175.1010476985114, 1207.5169932027259, 1264.633256355665, 14155.575332740034, 14874.851251487347, 16293.918530407273, 16474.659533315833, 17267.65464690699, 320367.0, 374414.0, 377069.0, 377256.0, 377279.0, 48595.5140448591, 55514.0, 55698.0, 55842.0, 56000.0, 1442.7691569349063, 1445.4146070841325, 1446.5298777897226, 1456.2209218068283, 1462.5027490653442, 26480.86759566267, 27725.30686732848, 29114.0, 29550.40899181961, 30249.848750756104, 50687.49312506828, 51057.0, 51223.74388128035, 51261.48738512716, 51275.23087153621, 276885.0, 278482.0, 278497.0, 279304.0, 279368.0, 34529.82735086309, 35240.0, 35386.64613353936, 35429.29141417209, 35549.289014220085, 46140.53859461362, 46516.0, 46703.29945050895, 46810.0, 47003.0, 38316.42525362066, 39110.41334379929, 40348.394774077824, 40662.1867562653, 40972.0, 35192.64807351894, 35324.293514130084, 35450.29099417946, 36109.45835812517, 36588.63411365959, 35623.93128206158, 35869.82065089658, 36259.637403625624, 36325.81837090903, 36351.63648363482, 29088.41823163567, 30600.0, 31237.531437028007, 31320.843395782875, 31562.68437315689, 24815.62776558317, 24831.25506234816, 26489.867550662122, 26719.530425826768, 28200.576991345555], "unit": "ms", "name": "ext.html", "value": 20013.09036355195}, {"lowerIsBetter": false, "replicates": [374.58608237897124, 380.89334986203863, 392.5034830938863, 393.9212157568487, 395.34767633404897, 15306.617334566641, 16118.516444506655, 17273.481795546122, 18734.437966860998, 18760.43718688438, 11769.882301176985, 12007.159498835084, 12080.0, 19463.610727785435, 19808.108635111417, 5110.667806592569, 5157.871053223681, 5194.428612852583, 5195.870103247411, 5200.583953283728, 3919.2945269851416, 3985.7409268397605, 3994.1811928554575, 4009.4587230723914, 5198.480151984809, 5463.098588732854, 5623.9437605623825, 5624.859378515529, 5627.2684551008515, 5633.971830140844, 5385.488378604026, 5480.945190548123, 5493.945060549383, 5508.283923090013, 5571.052921003439, 5287.101192797234, 5309.230161626581, 5316.521513063804, 5400.621956463049, 5605.131204663243, 13092.803607945889, 13143.934280328633, 13317.400716967759, 13337.733245335136, 13585.116967397187, 2247.5841969235744, 2265.512914723326, 2297.7357603875603, 2322.0131444136173, 2358.4575547624145, 9373.671921482715, 9623.759406014835, 9667.613295468173, 10041.297109202358, 10437.373757574494, 1907.1322548240655, 1919.1555715485142, 1952.9609407811906, 1955.4231501706888, 1992.4221975627052, 57046.14430783541, 58376.124358134664, 58857.0, 58881.70559147177, 59018.40981590172, 10742.462876856282, 10816.0, 10838.40388778609, 10860.891391086147, 10879.456027198608, 13965.2543432071, 14019.649508762159, 14055.508057217949, 14077.788833167511, 14156.292185390896, 8347.791305217297, 8417.326613870875, 8417.663293468193, 8463.0, 8483.830323393497, 8304.377171712145, 8308.252257296752, 8372.49765014089, 8444.957775211207, 8452.28155606768, 4566.931496027562, 4609.193391156534, 4653.046125544323, 4690.671652984258, 4699.765011749399, 1684.4412980429008, 1797.4338083503656, 1864.0959134819602, 1885.132838894124, 1891.442024602718, 441.1463817513122, 446.2458445227555, 939.3096074385321, 1034.2044695965824, 1126.718320419895, 9949.950250248703, 10742.462876856125, 10878.347299162075, 10944.726381840517, 11051.17116216271, 451.76282451713115, 452.1206253836323, 470.4025887123334, 481.62673927705606, 486.9123557759637, 5823.980803359395, 6024.518038556993, 6199.070139479114, 6209.099680546386, 6227.159333490012, 663767.0, 664027.0, 666695.0, 706589.0, 713257.0, 77540.61229693929, 79792.20207797848, 81277.0, 89549.55225223962, 91953.0, 541.5180489364469, 548.9917651235155, 552.5441510753644, 553.7259056766926, 553.7674176845735, 10772.569097236023, 11080.224384293344, 11285.943570282425, 11392.658220253406, 11459.369734664673, 37980.62019379771, 38650.80674596609, 39046.82859514219, 39056.60943390644, 39718.80140599279, 49924.25113623371, 50969.23546146885, 51961.7401912988, 52862.47137528729, 53153.7342313286, 10158.44128572906, 10184.490775461049, 10310.32982856112, 10352.585896564053, 10431.478426078515, 12273.324967126893, 12332.260064396165, 12401.441935112762, 12437.875621243671, 13098.148620339647, 15203.467878624195, 15447.150406727289, 15716.92141539285, 15905.284262207997, 15966.521004369888, 15063.322150503034, 15109.47116850905, 15404.691906162037, 15777.526674199793, 15784.0, 15709.135997520247, 15742.370305188122, 15813.604659883595, 15969.760453593437, 16027.2787724555, 9794.902050979685, 9920.751981200528, 9991.700248992542, 10168.949155254177, 10171.898281017095, 14096.295185240902, 14951.925240374163, 15502.224888755292, 15679.29443175037, 15874.365025398854], "unit": "ms", "name": "jquery.html", "value": 8344.13688731068}, {"lowerIsBetter": false, "replicates": [1417.844037155912, 1571.3164773323606, 1572.7011867745127, 1612.266418779455, 1692.9238184281705, 6788.830279243021, 7511.361534269587, 7808.063032436102, 7817.335526480256, 7818.413618978571, 7803.531788092719, 7943.245391687795, 7949.165337639537, 7967.043954725441, 7967.20327967203, 2040.2553068130173, 2061.041615648723, 2067.390119914621, 2320.153144102403, 2354.9411264718433, 2064.0299059442036, 2291.8510296830736, 2322.628379459287, 2322.7561106083785, 2323.1984965187066, 2265.716785401825, 2288.6567014947723, 2308.0998410619873, 2325.720913490375, 2329.196427232602, 2282.589133955887, 2297.517521320525, 2305.1816605105114, 2320.7447180810095, 2322.1872344679314, 2306.8615883047073, 2314.6875171851752, 2341.274204996457, 2347.471818840764, 2351.2240960483, 7837.843243135106, 7911.5253084815095, 7920.326772224368, 7924.643391047417, 7927.920720792776, 4794.18498855192, 4830.758462076882, 4871.391076115486, 4887.706737595756, 4904.705717656916, 3113.937721245585, 3158.3209609933974, 3192.7924684895406, 3221.774475786696, 3222.081706713599, 1008.6973907827696, 1019.0166489337751, 1032.8450732390127, 1054.504382940017, 1070.7644318249972, 10973.0, 10975.28660637056, 10980.780384392267, 10991.395473248967, 11004.119670426348, 4300.3549467580115, 4336.783160842008, 4392.341148827638, 4401.867943961686, 4402.84590039347, 9412.294077944045, 9423.811523769624, 9429.387089839138, 9439.24486041115, 9442.669506567374, 4894.975525122423, 4918.778654960535, 4921.803127874917, 4922.384701912261, 4923.433805112458, 4472.552744725566, 4501.122281155143, 4502.009557897258, 4505.234110201208, 4508.909821803545, 3410.0, 3471.2016236265295, 3753.1555400035318, 3770.5098337215986, 3842.8462861485227, 1142.1319796954374, 1210.0501106631252, 1213.0841214882707, 1219.939003049844, 1219.951201951912, 1132.8867113288604, 1152.5274637398627, 1154.255505199142, 1347.6630842289428, 1412.8869690424742, 7139.500234983501, 7152.284771522806, 7237.457190710613, 7254.056972593635, 7257.129144502694, 1126.718320419895, 1134.2400591603646, 1146.1976616368408, 1156.410230782307, 1162.3607016141088, 5986.49114825236, 6030.758769649165, 6031.969840150771, 6040.486558642477, 6043.063325184604, 9407.576659050359, 9563.187129094105, 9682.031796820262, 9689.90310096904, 9692.273079518924, 9521.857172142423, 9534.427934323963, 9535.713928582154, 9542.666006689733, 9543.570539325747, 7229.783106506708, 7288.0, 7288.12542494904, 7291.671874765649, 7583.886241706379, 7240.855182896207, 7266.309700578437, 7313.926860731324, 7314.634268286671, 7323.304286092813, 8489.235968762841, 8825.999160285533, 8850.070742571937, 8918.19736223743, 8946.463212207289, 8654.567271636519, 8772.473651580925, 8801.031886492608, 8815.955920220358, 8861.955690221508, 7122.18094919091, 7134.750283740044, 7182.928170718226, 7185.928140718735, 7188.38898693596, 6200.937990620036, 6297.0, 7375.557466552024, 7634.0, 7718.459707820623, 7228.855422891618, 7310.81722956909, 7318.597477138808, 7320.26797320285, 7418.109826820816, 7049.259827718016, 7054.858902822018, 7116.715331386687, 7129.180144283474, 7159.821004474929, 7249.166345870292, 7289.599072051088, 7291.270872912666, 7297.1973082961895, 7301.926980730124, 6477.77327793525, 6584.078229048033, 6605.5376123670885, 6609.272979972296, 6617.404433600999, 6246.250449946036, 6247.969085100907, 6249.1563638908165, 6279.089532017741, 6307.463865571478], "unit": "ms", "name": "mootools.html", "value": 4573.521893814546}, {"lowerIsBetter": false, "replicates": [339.7044571223036, 354.59753180140535, 358.6216541548665, 358.72736720092723, 362.1688225522427, 5949.405059494048, 6091.451769340759, 6107.114468402085, 6125.203723515942, 6159.815205543835, 5947.405259474051, 6082.482988945934, 6101.938980610193, 6109.297430795449, 6115.633062016283, 4687.343771871923, 4717.080169366975, 4719.976400117995, 4725.432948046239, 4728.314394412808, 4493.123840851035, 4577.267637178053, 4579.771011449431, 4703.106409782147, 4742.383490146276, 4671.789769460365, 4685.0, 4686.976565117169, 4687.929681054787, 4704.717716936978, 4688.648351373644, 4689.577937985564, 4695.0, 4696.272077827942, 4700.929486057695, 4129.607687269705, 4136.090060186754, 4137.482814648169, 4139.482564679415, 4140.0270936329935, 5710.543156547466, 5812.738426770763, 5815.360310365856, 5823.854403639901, 5824.679642619654, 5448.346198456211, 5456.781728730846, 5458.536024437927, 5465.098258787295, 5469.0702580561, 6152.292486364081, 6434.0, 6434.131392262031, 6436.839079023015, 6437.83905402364, 4447.466304043503, 4461.955380446187, 4558.658100642432, 4569.748663823489, 4570.542945705402, 6627.834304142339, 6648.501362397839, 6665.133532640774, 6674.432673222782, 6688.063671086052, 4635.119327327864, 4639.976800115978, 4644.489106198315, 4646.558576935186, 4647.233206520954, 4612.538746125428, 4689.601383882408, 4702.0, 4756.500567440438, 4767.95232047682, 5423.484768946943, 5433.918491222635, 5442.401335853053, 5462.617616766788, 5463.890722185613, 6470.38531339522, 6523.151990241333, 6531.608103513804, 6531.738730450728, 6533.673316334164, 1664.6670665866634, 1677.2620047179203, 1681.445123109371, 1682.8317168283074, 1684.7304431290934, 1068.0, 1068.0441005300213, 1087.3693257910393, 1095.3592148593002, 1166.6558365282262, 6295.0, 6332.176817013758, 6334.841628959313, 6336.778212762531, 6340.239171299474, 6657.400833924873, 6660.467162626978, 6660.733570657217, 6669.699863506154, 6672.2994085621285, 6304.873902521924, 6305.054241863757, 6324.241091069009, 6326.588771729823, 6328.7468501260355, 3563.8574457021946, 3658.0854786303425, 3658.176910195185, 3658.341498530237, 3675.5038069860225, 6990.580565166107, 7005.5796652200015, 7008.719651213996, 7012.193597736224, 7012.439004879598, 6853.485988550879, 6963.059986901805, 6974.337437943387, 6976.790696279119, 6981.371676549135, 6839.487038472134, 6846.110005699227, 6861.4853885958755, 6861.828454288683, 6947.235804061448, 6501.414872661483, 6571.57284776488, 6644.83387915306, 6760.93239067603, 6765.9661701691175, 6637.834054148685, 6644.60132392058, 6659.800205993828, 6687.899681504879, 6700.865982680221, 6943.236244013054, 6946.687399067156, 6953.582785032914, 6955.095837541087, 6963.9303606963285, 4500.639948804022, 4641.466231383434, 4647.883802904955, 4653.906921861476, 4685.976570117127, 6742.157230346207, 6743.561668491532, 6751.223609284995, 6752.763653272112, 6758.7296508139125, 6543.1821022372205, 6579.078928950046, 6582.440492557995, 6583.341665833378, 6590.209174899042, 6467.676616169267, 6526.0, 6565.868682626417, 6567.671616419256, 6570.178727659043, 6441.774537891339, 6442.806715798533, 6452.419282264619, 6452.9677351612945, 6454.354564543508, 6550.443212327005, 6564.704588293443, 6569.605823650596, 6570.605763654196, 6602.933970660232, 6336.3030066693545, 6435.839104022437, 6483.157189565326, 6498.220213574401, 6501.252355978933], "unit": "ms", "name": "prototype.html", "value": 4922.795906992104}, {"lowerIsBetter": false, "replicates": [682.2324884504932, 715.2489885620101, 746.6154930210939, 766.2261116272563, 800.5516910530101, 13628.727425451498, 13630.863691363083, 13663.590092297247, 13665.726685466298, 13680.931595342021, 12640.051996100283, 14522.491712790055, 14759.114453132792, 14760.302565205006, 14783.482578109768, 770.2182284980721, 781.9296263336298, 917.6375331743957, 931.483026920059, 931.8695382646434, 912.8356895758761, 923.9907600924006, 932.3007744191856, 936.5691781780389, 936.840737074699, 964.6527250189926, 970.2141265574871, 974.0211087856694, 974.0454354732367, 974.0843607009389, 797.6570074867805, 811.0632219786156, 931.5388882503137, 964.4020707161535, 968.2350942755215, 713.0, 714.2000958925984, 714.4213187318289, 714.9106361704787, 715.7602203261913, 13471.932640336834, 13475.86524134756, 13478.865211347858, 13484.865151348458, 13499.122557033761, 12390.442430090667, 12450.190737602026, 12682.175658582162, 12739.681507962283, 12848.614541563767, 10788.352698838095, 11347.092232621371, 11492.138089643311, 11735.354555499443, 11736.965683622127, 3908.609139086097, 3909.6285852843935, 3957.2085582883537, 3980.1641655252433, 3986.7009974252046, 47809.76095119537, 48220.27669584924, 53854.46145538534, 54998.17502737422, 55105.448945510034, 10064.69805905824, 10160.98390160978, 10188.847167292497, 10380.273380863267, 10474.319169253975, 18710.25158993652, 18776.155073021746, 18781.530461738566, 18817.529561761065, 18902.621947560972, 7783.922160778433, 7812.492188007761, 7815.53106813593, 7973.800654983555, 8014.398920081017, 4697.78859951303, 4965.379327584052, 4968.627352948543, 4969.204927211629, 4976.800927962841, 3298.0600528849163, 3329.883454079096, 3334.666533346646, 3344.414727422691, 3347.514610381481, 588.8498432899669, 597.9521638268928, 606.4905479397328, 609.701246389275, 618.0605479670875, 666.9399754022161, 667.89981502774, 688.1913751342153, 704.3519961635308, 712.8431745016088, 8378.371622128365, 8523.318134549221, 8557.786055348666, 8828.470291782514, 8889.066648001995, 523.1054896127664, 582.3215953414309, 645.5384400153913, 732.7031154855875, 765.8200322924108, 4236.724612900151, 4444.28891377378, 4462.598366147063, 4468.50846406895, 4598.793054312564, 60505.69747151236, 60694.69652651709, 61197.0, 61500.38499615036, 61592.0, 53751.73124134354, 53816.19275710868, 54229.0, 54507.18239226415, 54519.45480545223, 47521.76239118852, 48735.51264487381, 49377.506224938006, 49856.750716246184, 49950.0, 9264.490453025148, 9347.485888276211, 9361.672341468016, 9407.670731524364, 9660.468674222986, 35165.64834351624, 36147.819260903525, 36239.81880090583, 36560.26879462343, 36741.08147296339, 43022.13955720805, 43080.35379469373, 44845.775771122244, 46682.76658616685, 46923.0, 11311.151663625425, 11557.13321500874, 11917.940410297893, 11990.880091198977, 12589.496420143092, 12228.44971976281, 12859.16415432994, 13681.384337705027, 13752.31238438054, 13806.93096534511, 12041.638750837488, 12627.242365458102, 12634.0, 12850.485980560672, 12981.545645902348, 11199.21605487608, 11467.254628449124, 11682.47428865686, 11812.114091443347, 11844.644660660195, 9919.256055795988, 11920.523179073087, 11959.461824217757, 12236.510539578318, 12813.743725125632, 9245.676401325922, 10484.423356715453, 10708.946455267675, 10777.730556736144, 10957.287776294515, 10443.530041148015, 10568.260221784401, 10903.127749780157, 11157.776844463227, 11301.547938082385], "unit": "ms", "name": "yui.html", "value": 6708.8313257542195}], "name": "dromaeo_css", "value": 7182.852396046446}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 103.0, 110.0, 111.0, 109.0, 100.0, 108.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.4}, {"lowerIsBetter": true, "replicates": [150.0, 122.0, 144.0, 163.0, 122.0, 145.0, 148.0, 123.0, 157.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.8}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 160.0, 160.0, 162.0, 159.0, 159.0, 159.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.9}, {"lowerIsBetter": true, "replicates": [77.0, 71.0, 75.0, 150.0, 71.0, 71.0, 71.0, 72.0, 151.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 88.3}, {"lowerIsBetter": true, "replicates": [93.0, 91.0, 139.0, 92.0, 93.0, 93.0, 139.0, 92.0, 93.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 108.0, 109.0, 108.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 108.9}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 147.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [56.0, 57.0, 57.0, 56.0, 58.0, 56.0, 58.0, 58.0, 55.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 56.8}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.9}, {"lowerIsBetter": true, "replicates": [78.0, 79.0, 81.0, 83.0, 88.0, 80.0, 81.0, 82.0, 80.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.2}, {"lowerIsBetter": true, "replicates": [124.0, 112.0, 113.0, 114.0, 126.0, 111.0, 137.0, 119.0, 119.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.4}, {"lowerIsBetter": true, "replicates": [135.0, 157.0, 142.0, 144.0, 154.0, 139.0, 137.0, 143.0, 141.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.5}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 64.0, 65.0, 64.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1482.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.82610845557937], "unit": "score", "name": "Richards", "value": 214.177234}, {"lowerIsBetter": false, "replicates": [167.53224995811695], "unit": "score", "name": "DeltaBlue", "value": 394.6583420000001}, {"lowerIsBetter": false, "replicates": [279.72027972027973], "unit": "score", "name": "Encrypt", "value": 951.5970750000004}, {"lowerIsBetter": false, "replicates": [5128.205128205128], "unit": "score", "name": "Decrypt", "value": 51.90529499999997}, {"lowerIsBetter": false, "replicates": [984.2519685039371], "unit": "score", "name": "RayTrace", "value": 751.8288240000002}, {"lowerIsBetter": false, "replicates": [558.0357142857143], "unit": "score", "name": "Earley", "value": 1194.3016960000004}, {"lowerIsBetter": false, "replicates": [11087.912087912087], "unit": "score", "name": "Boyer", "value": 60.107168483647186}, {"lowerIsBetter": false, "replicates": [24261.904761904763], "unit": "score", "name": "RegExp", "value": 37.547958783120734}, {"lowerIsBetter": false, "replicates": [529.6610169491526], "unit": "score", "name": "Splay", "value": 153.85500800000003}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23005.217778973}]} 09:22:29 INFO - RETURN: dromaeo_css: 7182.85 09:22:29 INFO - RETURN: kraken: 1482.50 09:22:29 INFO - RETURN: v8_7: 23005.22 09:22:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "23005.22"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1482.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7182.85"}}} 09:22:29 INFO - Return code: 0 09:22:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:29 INFO - # TBPL SUCCESS # 09:22:29 INFO - Running post-action listener: _resource_record_post_action 09:22:29 INFO - Running post-run listener: _resource_record_post_run 09:22:30 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 262144; Write bytes: 501714944; Read time: 16; Write time: 456392 09:22:30 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 237965312; Read time: 0; Write time: 258452 09:22:30 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 262144; Write bytes: 263749632; Read time: 16; Write time: 197940 09:22:30 INFO - Running post-run listener: _upload_blobber_files 09:22:30 INFO - Blob upload gear active. 09:22:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:22:30 INFO - Copying logs to upload dir... 09:22:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.608312 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-01-22 09:22:30.576841) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:22:30.580156) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010057 build_url: 'https://queue.taskcluster.net/v1/task/bUODrfdIQQynOXWD0dtxhg/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-22 09:22:30.630539) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:22:30.630875) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453482085.290302-324734387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004607 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:22:30.680542) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 09:22:30.680862) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 09:22:30.681175) ========= ========= Total master_lag: 0.23 =========