builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-049 starttime: 1453483407.39 results: success (0) buildid: 20160122065828 builduid: 3d3b5011b7644a1e8b60ac2ad98ea64c revision: 8e913ace1e198c34423bfadc807296f6400078bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.386014) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.386473) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.386945) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006292 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.413603) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.413981) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.460536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.460874) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.036798 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.520151) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.520474) ========= 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:23:27.520876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.521189) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-22 09:23:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2016-01-22 09:23:27 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.700989) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.701349) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.055755 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.769053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:27.769456) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8e913ace1e198c34423bfadc807296f6400078bd --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 8e913ace1e198c34423bfadc807296f6400078bd --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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-22 09:23:27,912 truncating revision to first 12 chars 2016-01-22 09:23:27,913 Setting DEBUG logging. 2016-01-22 09:23:27,913 attempt 1/10 2016-01-22 09:23:27,913 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8e913ace1e19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-22 09:23:28,139 unpacking tar archive at: mozilla-aurora-8e913ace1e19/testing/mozharness/ program finished with exit code 0 elapsedTime=0.522352 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:28.318423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:28.318841) ========= echo 8e913ace1e198c34423bfadc807296f6400078bd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'8e913ace1e198c34423bfadc807296f6400078bd'] 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 8e913ace1e198c34423bfadc807296f6400078bd program finished with exit code 0 elapsedTime=0.004625 script_repo_revision: '8e913ace1e198c34423bfadc807296f6400078bd' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:28.373762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:28.374486) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 09:23:28.385610) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-01-22 09:23:28.385936) ========= /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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:23:28 INFO - MultiFileLogger online at 20160122 09:23:28 in /builds/slave/test-pgo 09:23: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:23:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:23:28 INFO - {'append_to_log': False, 09:23:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:23:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:23:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:23:28 INFO - 'branch': 'Mozilla-Aurora', 09:23:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:23:28 INFO - 'config_files': ('talos/linux_config.py',), 09:23:28 INFO - 'default_actions': ('clobber', 09:23:28 INFO - 'read-buildbot-config', 09:23:28 INFO - 'download-and-extract', 09:23:28 INFO - 'populate-webroot', 09:23:28 INFO - 'create-virtualenv', 09:23:28 INFO - 'install', 09:23:28 INFO - 'run-tests'), 09:23:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:23:28 INFO - 'download_minidump_stackwalk': True, 09:23:28 INFO - 'download_symbols': 'ondemand', 09:23:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:23:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:23:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:23:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:23:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:23:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:23:28 INFO - 'installer_path': 'installer.exe', 09:23:28 INFO - 'log_level': 'info', 09:23:28 INFO - 'log_name': 'talos', 09:23:28 INFO - 'log_to_console': True, 09:23:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:23:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:23:28 INFO - 'opt_config_files': (), 09:23:28 INFO - 'pip_index': False, 09:23:28 INFO - 'sps_profile': False, 09:23:28 INFO - 'sps_profile_interval': 0, 09:23:28 INFO - 'suite': 'dromaeojs', 09:23:28 INFO - 'system_bits': '32', 09:23:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:23:28 INFO - 'title': 'talos-linux64-ix-049', 09:23:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:23:28 INFO - 'use_talos_json': True, 09:23:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:23:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:23:28 INFO - 'work_dir': 'build'} 09:23:28 INFO - ##### 09:23:28 INFO - ##### Running clobber step. 09:23:28 INFO - ##### 09:23:28 INFO - Running pre-action listener: _resource_record_pre_action 09:23:28 INFO - Running main action method: clobber 09:23:28 INFO - rmtree: /builds/slave/test-pgo/build 09:23:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:23:29 INFO - Running post-action listener: _resource_record_post_action 09:23:29 INFO - ##### 09:23:29 INFO - ##### Running read-buildbot-config step. 09:23:29 INFO - ##### 09:23:29 INFO - Running pre-action listener: _resource_record_pre_action 09:23:29 INFO - Running main action method: read_buildbot_config 09:23:29 INFO - Using buildbot properties: 09:23:29 INFO - { 09:23:29 INFO - "properties": { 09:23:29 INFO - "buildnumber": 210, 09:23:29 INFO - "product": "firefox", 09:23:29 INFO - "script_repo_revision": "production", 09:23:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:23:29 INFO - "repository": "", 09:23:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:23:29 INFO - "buildid": "20160122065828", 09:23:29 INFO - "slavename": "talos-linux64-ix-049", 09:23:29 INFO - "pgo_build": "True", 09:23:29 INFO - "basedir": "/builds/slave/test-pgo", 09:23:29 INFO - "project": "", 09:23:29 INFO - "platform": "ubuntu64_hw", 09:23:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:23:29 INFO - "slavebuilddir": "test", 09:23:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:23:29 INFO - "branch": "mozilla-aurora", 09:23:29 INFO - "repo_path": "releases/mozilla-aurora", 09:23:29 INFO - "stage_platform": "linux64-pgo", 09:23:29 INFO - "builduid": "3d3b5011b7644a1e8b60ac2ad98ea64c", 09:23:29 INFO - "revision": "8e913ace1e198c34423bfadc807296f6400078bd" 09:23:29 INFO - }, 09:23:29 INFO - "sourcestamp": { 09:23:29 INFO - "repository": "", 09:23:29 INFO - "hasPatch": false, 09:23:29 INFO - "project": "", 09:23:29 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:23:29 INFO - "changes": [ 09:23:29 INFO - { 09:23:29 INFO - "category": null, 09:23:29 INFO - "files": [ 09:23:29 INFO - { 09:23:29 INFO - "url": null, 09:23:29 INFO - "name": "https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 09:23:29 INFO - } 09:23:29 INFO - ], 09:23:29 INFO - "repository": "", 09:23:29 INFO - "rev": "8e913ace1e198c34423bfadc807296f6400078bd", 09:23:29 INFO - "who": "cbook@mozilla.com", 09:23:29 INFO - "when": 1453483352, 09:23:29 INFO - "number": 7501535, 09:23:29 INFO - "comments": "Bug 1230857 - Make GMPInstallManager enforce sensible permissions on GMP files at install time. r=spohl, a=sylvestre\n\nThis ensures that GMP packages with bad permissions will still be usable. For\nexample, a GMP without execute/read permissions in its zip won't work without\nthis.", 09:23:29 INFO - "project": "", 09:23:29 INFO - "at": "Fri 22 Jan 2016 09:22:32", 09:23:29 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:23:29 INFO - "revlink": "", 09:23:29 INFO - "properties": [ 09:23:29 INFO - [ 09:23:29 INFO - "buildid", 09:23:29 INFO - "20160122065828", 09:23:29 INFO - "Change" 09:23:29 INFO - ], 09:23:29 INFO - [ 09:23:29 INFO - "builduid", 09:23:29 INFO - "3d3b5011b7644a1e8b60ac2ad98ea64c", 09:23:29 INFO - "Change" 09:23:29 INFO - ], 09:23:29 INFO - [ 09:23:29 INFO - "pgo_build", 09:23:29 INFO - "True", 09:23:29 INFO - "Change" 09:23:29 INFO - ] 09:23:29 INFO - ], 09:23:29 INFO - "revision": "8e913ace1e198c34423bfadc807296f6400078bd" 09:23:29 INFO - } 09:23:29 INFO - ], 09:23:29 INFO - "revision": "8e913ace1e198c34423bfadc807296f6400078bd" 09:23:29 INFO - } 09:23:29 INFO - } 09:23:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 09:23:29 INFO - Running post-action listener: _resource_record_post_action 09:23:29 INFO - ##### 09:23:29 INFO - ##### Running download-and-extract step. 09:23:29 INFO - ##### 09:23:29 INFO - Running pre-action listener: _resource_record_pre_action 09:23:29 INFO - Running main action method: download_and_extract 09:23:29 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:23: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:23:29 INFO - https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:23:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/test_packages.json 09:23:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/test_packages.json 09:23:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:23:32 INFO - Downloaded 1302 bytes. 09:23:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:23:32 INFO - Using the following test package requirements: 09:23:32 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:23:32 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:23:32 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'jsshell-linux-x86_64.zip'], 09:23:32 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:23:32 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:23:32 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:23:32 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:23:32 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:23:32 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:23:32 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:23:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:23:32 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:23:32 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:23:32 INFO - https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:23:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:23:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:23:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:33 INFO - Downloaded 23159075 bytes. 09:23:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:23:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:23:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:23:34 INFO - Return code: 0 09:23:34 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:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:34 INFO - https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:23:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:23:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:23:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:36 INFO - Downloaded 23159075 bytes. 09:23: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:23:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:23: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:23:37 INFO - Return code: 0 09:23: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:23:37 INFO - https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:23:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:23:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:23:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:39 INFO - Downloaded 11132038 bytes. 09:23: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:23:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:23: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:23:39 INFO - Return code: 0 09:23: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:23:39 INFO - https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:23:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:23:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:23:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDxVibeIQAelFrXkPXgRVQ/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:23:40 INFO - Downloaded 58175751 bytes. 09:23:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:23:40 INFO - mkdir: /builds/slave/test-pgo/properties 09:23:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:23:40 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:23:40 INFO - Contents: 09:23:40 INFO - build_url:https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:23:40 INFO - Running post-action listener: _resource_record_post_action 09:23:40 INFO - Running post-action listener: set_extra_try_arguments 09:23:40 INFO - ##### 09:23:40 INFO - ##### Running populate-webroot step. 09:23:40 INFO - ##### 09:23:40 INFO - Running pre-action listener: _resource_record_pre_action 09:23:40 INFO - Running main action method: populate_webroot 09:23:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:23:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:23:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:23:40 INFO - '--tppagecycles', 09:23:40 INFO - '7'], 09:23:40 INFO - 'tests': ['tsvgm']}}, 09:23:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:23:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:23:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:23:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:23:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'tests': ['damp', 'tps']}, 09:23:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['damp', 'tps']}, 09:23:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:23:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:23:40 INFO - 'other': {'tests': ['a11yr', 09:23:40 INFO - 'ts_paint', 09:23:40 INFO - 'tpaint', 09:23:40 INFO - 'sessionrestore', 09:23:40 INFO - 'sessionrestore_no_auto_restore']}, 09:23:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['a11yr', 09:23:40 INFO - 'ts_paint', 09:23:40 INFO - 'tpaint', 09:23:40 INFO - 'sessionrestore', 09:23:40 INFO - 'sessionrestore_no_auto_restore']}, 09:23:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['a11yr', 09:23:40 INFO - 'ts_paint', 09:23:40 INFO - 'tpaint', 09:23:40 INFO - 'sessionrestore', 09:23:40 INFO - 'sessionrestore_no_auto_restore']}, 09:23:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['a11yr', 09:23:40 INFO - 'ts_paint', 09:23:40 INFO - 'tpaint', 09:23:40 INFO - 'sessionrestore', 09:23:40 INFO - 'sessionrestore_no_auto_restore']}, 09:23:40 INFO - 'other_l64': {'tests': ['a11yr', 09:23:40 INFO - 'ts_paint', 09:23:40 INFO - 'tpaint', 09:23:40 INFO - 'sessionrestore', 09:23:40 INFO - 'sessionrestore_no_auto_restore']}, 09:23:40 INFO - 'other_nol64': {'tests': ['a11yr', 09:23:40 INFO - 'ts_paint', 09:23:40 INFO - 'tpaint', 09:23:40 INFO - 'sessionrestore', 09:23:40 INFO - 'sessionrestore_no_auto_restore']}, 09:23:40 INFO - 'svgr': {'tests': ['tsvgx', 09:23:40 INFO - 'tsvgr_opacity', 09:23:40 INFO - 'tart', 09:23:40 INFO - 'tscrollx', 09:23:40 INFO - 'cart']}, 09:23:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['tsvgx', 09:23:40 INFO - 'tsvgr_opacity', 09:23:40 INFO - 'tart', 09:23:40 INFO - 'tscrollx', 09:23:40 INFO - 'cart']}, 09:23:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'tests': ['tp5o']}, 09:23:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'talos_options': ['--e10s'], 09:23:40 INFO - 'tests': ['tp5o']}, 09:23:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'talos_options': ['--xperf_path', 09:23:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:40 INFO - 'tests': ['tp5n']}, 09:23:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:40 INFO - 'talos_options': ['--e10s', 09:23:40 INFO - '--xperf_path', 09:23:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:40 INFO - 'tests': ['tp5n']}}, 09:23:40 INFO - 'talos.zip': {'path': '', 09:23:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:23:40 INFO - Running post-action listener: _resource_record_post_action 09:23:40 INFO - ##### 09:23:40 INFO - ##### Running create-virtualenv step. 09:23:40 INFO - ##### 09:23:40 INFO - Running pre-action listener: _resource_record_pre_action 09:23:40 INFO - Running main action method: create_virtualenv 09:23:40 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:23:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:23:40 INFO - Using real prefix '/usr' 09:23:40 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:23:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:23:43 INFO - Installing pip.................done. 09:23:43 INFO - Return code: 0 09:23:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23: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:23: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:23:43 INFO - Using env: {'DISPLAY': ':0', 09:23:43 INFO - 'HOME': '/home/cltbld', 09:23:43 INFO - 'LANG': 'en_US.UTF-8', 09:23:43 INFO - 'LANGUAGE': 'en_US:en', 09:23:43 INFO - 'LOGNAME': 'cltbld', 09:23:43 INFO - 'MAIL': '/var/mail/cltbld', 09:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:43 INFO - 'MOZ_NO_REMOTE': '1', 09:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:43 INFO - 'NO_EM_RESTART': '1', 09:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:43 INFO - 'SHELL': '/bin/bash', 09:23:43 INFO - 'SHLVL': '1', 09:23:43 INFO - 'TERM': 'linux', 09:23:43 INFO - 'TMOUT': '86400', 09:23:43 INFO - 'USER': 'cltbld', 09:23:43 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:43 INFO - '_': '/tools/buildbot/bin/python'} 09:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23: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:23:43 INFO - Cleaning up... 09:23:43 INFO - Return code: 0 09:23:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23: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:23: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:23:43 INFO - Using env: {'DISPLAY': ':0', 09:23:43 INFO - 'HOME': '/home/cltbld', 09:23:43 INFO - 'LANG': 'en_US.UTF-8', 09:23:43 INFO - 'LANGUAGE': 'en_US:en', 09:23:43 INFO - 'LOGNAME': 'cltbld', 09:23:43 INFO - 'MAIL': '/var/mail/cltbld', 09:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:43 INFO - 'MOZ_NO_REMOTE': '1', 09:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:43 INFO - 'NO_EM_RESTART': '1', 09:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:43 INFO - 'SHELL': '/bin/bash', 09:23:43 INFO - 'SHLVL': '1', 09:23:43 INFO - 'TERM': 'linux', 09:23:43 INFO - 'TMOUT': '86400', 09:23:43 INFO - 'USER': 'cltbld', 09:23:43 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:43 INFO - '_': '/tools/buildbot/bin/python'} 09:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:43 INFO - Downloading/unpacking psutil>=0.7.1 09:23: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:23: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:23: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:23: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:23: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:23: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:23:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:23:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:45 INFO - Installing collected packages: psutil 09:23:45 INFO - Running setup.py install for psutil 09:23:45 INFO - building 'psutil._psutil_linux' extension 09:23: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:23:45 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:23:45 INFO - building 'psutil._psutil_posix' extension 09:23:45 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:23:45 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:23:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:46 INFO - Successfully installed psutil 09:23:46 INFO - Cleaning up... 09:23:46 INFO - Return code: 0 09:23:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23: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:23: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:23:46 INFO - Using env: {'DISPLAY': ':0', 09:23:46 INFO - 'HOME': '/home/cltbld', 09:23:46 INFO - 'LANG': 'en_US.UTF-8', 09:23:46 INFO - 'LANGUAGE': 'en_US:en', 09:23:46 INFO - 'LOGNAME': 'cltbld', 09:23:46 INFO - 'MAIL': '/var/mail/cltbld', 09:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:46 INFO - 'MOZ_NO_REMOTE': '1', 09:23:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:46 INFO - 'NO_EM_RESTART': '1', 09:23:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:46 INFO - 'SHELL': '/bin/bash', 09:23:46 INFO - 'SHLVL': '1', 09:23:46 INFO - 'TERM': 'linux', 09:23:46 INFO - 'TMOUT': '86400', 09:23:46 INFO - 'USER': 'cltbld', 09:23:46 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:46 INFO - '_': '/tools/buildbot/bin/python'} 09:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:23: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:23: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:23: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:23: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:23: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:23: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:23:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:23:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:23: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:23:48 INFO - Installing collected packages: mozsystemmonitor 09:23:48 INFO - Running setup.py install for mozsystemmonitor 09:23:48 INFO - Successfully installed mozsystemmonitor 09:23:48 INFO - Cleaning up... 09:23:49 INFO - Return code: 0 09:23:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:23:49 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:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:49 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:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:49 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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23:49 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:23:49 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:23:49 INFO - Using env: {'DISPLAY': ':0', 09:23:49 INFO - 'HOME': '/home/cltbld', 09:23:49 INFO - 'LANG': 'en_US.UTF-8', 09:23:49 INFO - 'LANGUAGE': 'en_US:en', 09:23:49 INFO - 'LOGNAME': 'cltbld', 09:23:49 INFO - 'MAIL': '/var/mail/cltbld', 09:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:49 INFO - 'MOZ_NO_REMOTE': '1', 09:23:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:49 INFO - 'NO_EM_RESTART': '1', 09:23:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:49 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:49 INFO - 'SHELL': '/bin/bash', 09:23:49 INFO - 'SHLVL': '1', 09:23:49 INFO - 'TERM': 'linux', 09:23:49 INFO - 'TMOUT': '86400', 09:23:49 INFO - 'USER': 'cltbld', 09:23:49 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:49 INFO - '_': '/tools/buildbot/bin/python'} 09:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:49 INFO - Downloading/unpacking blobuploader==1.2.4 09:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:50 INFO - Downloading blobuploader-1.2.4.tar.gz 09:23:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:23:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:23:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 INFO - Downloading docopt-0.6.1.tar.gz 09:23:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:23:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:23:51 INFO - Running setup.py install for blobuploader 09:23:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:23:51 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:23:51 INFO - Running setup.py install for requests 09:23:52 INFO - Running setup.py install for docopt 09:23:52 INFO - Successfully installed blobuploader requests docopt 09:23:52 INFO - Cleaning up... 09:23:52 INFO - Return code: 0 09:23:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:23:52 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:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:52 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:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:52 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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23:52 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:23:52 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:23:52 INFO - Using env: {'DISPLAY': ':0', 09:23:52 INFO - 'HOME': '/home/cltbld', 09:23:52 INFO - 'LANG': 'en_US.UTF-8', 09:23:52 INFO - 'LANGUAGE': 'en_US:en', 09:23:52 INFO - 'LOGNAME': 'cltbld', 09:23:52 INFO - 'MAIL': '/var/mail/cltbld', 09:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:52 INFO - 'MOZ_NO_REMOTE': '1', 09:23:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:52 INFO - 'NO_EM_RESTART': '1', 09:23:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:52 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:52 INFO - 'SHELL': '/bin/bash', 09:23:52 INFO - 'SHLVL': '1', 09:23:52 INFO - 'TERM': 'linux', 09:23:52 INFO - 'TMOUT': '86400', 09:23:52 INFO - 'USER': 'cltbld', 09:23:52 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:52 INFO - '_': '/tools/buildbot/bin/python'} 09:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:23:52 INFO - Running setup.py (path:/tmp/pip-KgGyIP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:23:52 INFO - Running setup.py (path:/tmp/pip-X2GbnT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:23:52 INFO - Running setup.py (path:/tmp/pip-MwLtGt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:23:52 INFO - Running setup.py (path:/tmp/pip-gmTqyN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:23:52 INFO - Running setup.py (path:/tmp/pip-dSBT2x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:23:52 INFO - Running setup.py (path:/tmp/pip-WJ8NwX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:23:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:23:52 INFO - Running setup.py (path:/tmp/pip-gwHSdT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:23:53 INFO - Running setup.py (path:/tmp/pip-01sSY0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:23:53 INFO - Running setup.py (path:/tmp/pip-2ZCe7Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:23:53 INFO - Running setup.py (path:/tmp/pip-LEyxID-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:23:53 INFO - Running setup.py (path:/tmp/pip-XcaKC_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:23:53 INFO - Running setup.py (path:/tmp/pip-TcUvLi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:23:53 INFO - Running setup.py (path:/tmp/pip-eHpxQe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:23:53 INFO - Running setup.py (path:/tmp/pip-3q2FsX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:23:53 INFO - Running setup.py (path:/tmp/pip-SBuzFE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:23:53 INFO - Running setup.py (path:/tmp/pip-YTKLaW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:23:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:23:53 INFO - Running setup.py (path:/tmp/pip-tVtRp5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:23:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:23:53 INFO - Running setup.py install for manifestparser 09:23:53 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:23:53 INFO - Running setup.py install for mozcrash 09:23:53 INFO - Running setup.py install for mozdebug 09:23:54 INFO - Running setup.py install for mozdevice 09:23:54 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Running setup.py install for mozfile 09:23:54 INFO - Running setup.py install for mozhttpd 09:23:54 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Running setup.py install for mozinfo 09:23:54 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Running setup.py install for mozInstall 09:23:54 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Running setup.py install for mozleak 09:23:54 INFO - Running setup.py install for mozlog 09:23:54 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Running setup.py install for moznetwork 09:23:54 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:23:54 INFO - Running setup.py install for mozprocess 09:23:54 INFO - Running setup.py install for mozprofile 09:23:55 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:23:55 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:23:55 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:23:55 INFO - Running setup.py install for mozrunner 09:23:55 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:23:55 INFO - Running setup.py install for mozscreenshot 09:23:55 INFO - Running setup.py install for moztest 09:23:55 INFO - Running setup.py install for mozversion 09:23:55 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:23:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:23:55 INFO - Cleaning up... 09:23:55 INFO - Return code: 0 09:23:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23: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:23: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:23:55 INFO - Using env: {'DISPLAY': ':0', 09:23:55 INFO - 'HOME': '/home/cltbld', 09:23:55 INFO - 'LANG': 'en_US.UTF-8', 09:23:55 INFO - 'LANGUAGE': 'en_US:en', 09:23:55 INFO - 'LOGNAME': 'cltbld', 09:23:55 INFO - 'MAIL': '/var/mail/cltbld', 09:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:55 INFO - 'MOZ_NO_REMOTE': '1', 09:23:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:55 INFO - 'NO_EM_RESTART': '1', 09:23:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:55 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:55 INFO - 'SHELL': '/bin/bash', 09:23:55 INFO - 'SHLVL': '1', 09:23:55 INFO - 'TERM': 'linux', 09:23:55 INFO - 'TMOUT': '86400', 09:23:55 INFO - 'USER': 'cltbld', 09:23:55 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:55 INFO - '_': '/tools/buildbot/bin/python'} 09:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:23:55 INFO - Running setup.py (path:/tmp/pip-Hfl6xo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:23: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:23:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:23:55 INFO - Running setup.py (path:/tmp/pip-4aSm6H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:23: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:23:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:23:55 INFO - Running setup.py (path:/tmp/pip-2ioaog-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:23: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:23:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:23:55 INFO - Running setup.py (path:/tmp/pip-5ArvVx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:23:56 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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:23:56 INFO - Running setup.py (path:/tmp/pip-MM95du-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:23:56 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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:23:56 INFO - Running setup.py (path:/tmp/pip-gEx8oU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:23:56 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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:23:56 INFO - Running setup.py (path:/tmp/pip-jIY52X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:23:56 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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:23:56 INFO - Running setup.py (path:/tmp/pip-hnHisA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:23:56 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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:23:56 INFO - Running setup.py (path:/tmp/pip-wr7Zzq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:23:56 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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:23:56 INFO - Running setup.py (path:/tmp/pip-WsSliy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:23:56 INFO - Running setup.py (path:/tmp/pip-jjXmtu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:23:56 INFO - Running setup.py (path:/tmp/pip-8q_trJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:23:56 INFO - Running setup.py (path:/tmp/pip-6SXg3x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:23:56 INFO - Running setup.py (path:/tmp/pip-eaIX1M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:23:56 INFO - Running setup.py (path:/tmp/pip-sIuj62-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:23:56 INFO - Running setup.py (path:/tmp/pip-184kek-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:23: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:23:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:23:56 INFO - Running setup.py (path:/tmp/pip-IfLSBG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23:58 INFO - Downloading blessings-1.6.tar.gz 09:23:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:23:58 INFO - Installing collected packages: blessings 09:23:58 INFO - Running setup.py install for blessings 09:23:58 INFO - Successfully installed blessings 09:23:58 INFO - Cleaning up... 09:23:58 INFO - Return code: 0 09:23:58 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:23:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:23:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:23:59 INFO - Reading from file tmpfile_stdout 09:23:59 INFO - Current package versions: 09:23:59 INFO - argparse == 1.2.1 09:23:59 INFO - blessings == 1.6 09:23:59 INFO - blobuploader == 1.2.4 09:23:59 INFO - docopt == 0.6.1 09:23:59 INFO - manifestparser == 1.1 09:23:59 INFO - mozInstall == 1.12 09:23:59 INFO - mozcrash == 0.16 09:23:59 INFO - mozdebug == 0.1 09:23:59 INFO - mozdevice == 0.47 09:23:59 INFO - mozfile == 1.2 09:23:59 INFO - mozhttpd == 0.7 09:23:59 INFO - mozinfo == 0.9 09:23:59 INFO - mozleak == 0.1 09:23:59 INFO - mozlog == 3.1 09:23:59 INFO - moznetwork == 0.27 09:23:59 INFO - mozprocess == 0.22 09:23:59 INFO - mozprofile == 0.27 09:23:59 INFO - mozrunner == 6.11 09:23:59 INFO - mozscreenshot == 0.1 09:23:59 INFO - mozsystemmonitor == 0.0 09:23:59 INFO - moztest == 0.7 09:23:59 INFO - mozversion == 1.4 09:23:59 INFO - psutil == 3.1.1 09:23:59 INFO - requests == 1.2.3 09:23:59 INFO - wsgiref == 0.1.2 09:23:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:59 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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23:59 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:23:59 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:23:59 INFO - Using env: {'DISPLAY': ':0', 09:23:59 INFO - 'HOME': '/home/cltbld', 09:23:59 INFO - 'LANG': 'en_US.UTF-8', 09:23:59 INFO - 'LANGUAGE': 'en_US:en', 09:23:59 INFO - 'LOGNAME': 'cltbld', 09:23:59 INFO - 'MAIL': '/var/mail/cltbld', 09:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:59 INFO - 'MOZ_NO_REMOTE': '1', 09:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:59 INFO - 'NO_EM_RESTART': '1', 09:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:59 INFO - 'SHELL': '/bin/bash', 09:23:59 INFO - 'SHLVL': '1', 09:23:59 INFO - 'TERM': 'linux', 09:23:59 INFO - 'TMOUT': '86400', 09:23:59 INFO - 'USER': 'cltbld', 09:23:59 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:59 INFO - '_': '/tools/buildbot/bin/python'} 09:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23: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:23:59 INFO - Cleaning up... 09:23:59 INFO - Return code: 0 09:23:59 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:23: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:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:23: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:23: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:23:59 INFO - Using env: {'DISPLAY': ':0', 09:23:59 INFO - 'HOME': '/home/cltbld', 09:23:59 INFO - 'LANG': 'en_US.UTF-8', 09:23:59 INFO - 'LANGUAGE': 'en_US:en', 09:23:59 INFO - 'LOGNAME': 'cltbld', 09:23:59 INFO - 'MAIL': '/var/mail/cltbld', 09:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:59 INFO - 'MOZ_NO_REMOTE': '1', 09:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:59 INFO - 'NO_EM_RESTART': '1', 09:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:23:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:23:59 INFO - 'SHELL': '/bin/bash', 09:23:59 INFO - 'SHLVL': '1', 09:23:59 INFO - 'TERM': 'linux', 09:23:59 INFO - 'TMOUT': '86400', 09:23:59 INFO - 'USER': 'cltbld', 09:23:59 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:59 INFO - '_': '/tools/buildbot/bin/python'} 09:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:59 INFO - Downloading/unpacking jsonschema 09:23: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:23: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:23: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:23: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:23: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:23: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:24:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:01 INFO - Downloading/unpacking functools32 (from jsonschema) 09:24: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:24: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:24: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:24: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:24: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:24: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:24:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:01 INFO - warning: no files found matching '*.txt' 09:24:01 INFO - no previously-included directories found matching 'build' 09:24:01 INFO - no previously-included directories found matching 'dist' 09:24:01 INFO - no previously-included directories found matching '.git*' 09:24:01 INFO - Installing collected packages: jsonschema, functools32 09:24:01 INFO - Running setup.py install for functools32 09:24:01 INFO - warning: no files found matching '*.txt' 09:24:01 INFO - no previously-included directories found matching 'build' 09:24:01 INFO - no previously-included directories found matching 'dist' 09:24:01 INFO - no previously-included directories found matching '.git*' 09:24:01 INFO - Successfully installed jsonschema functools32 09:24:01 INFO - Cleaning up... 09:24:01 INFO - Return code: 0 09:24:01 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:24: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:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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 0x7f7597af4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', '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:24: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:24: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:24:01 INFO - Using env: {'DISPLAY': ':0', 09:24:01 INFO - 'HOME': '/home/cltbld', 09:24:01 INFO - 'LANG': 'en_US.UTF-8', 09:24:01 INFO - 'LANGUAGE': 'en_US:en', 09:24:01 INFO - 'LOGNAME': 'cltbld', 09:24:01 INFO - 'MAIL': '/var/mail/cltbld', 09:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:01 INFO - 'MOZ_NO_REMOTE': '1', 09:24:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:01 INFO - 'NO_EM_RESTART': '1', 09:24:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:24:01 INFO - 'PWD': '/builds/slave/test-pgo', 09:24:01 INFO - 'SHELL': '/bin/bash', 09:24:01 INFO - 'SHLVL': '1', 09:24:01 INFO - 'TERM': 'linux', 09:24:01 INFO - 'TMOUT': '86400', 09:24:01 INFO - 'USER': 'cltbld', 09:24:01 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:01 INFO - '_': '/tools/buildbot/bin/python'} 09:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:02 INFO - Downloading/unpacking flake8 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:24:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:24: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:24: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:24: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:24: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:24: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:24: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:24:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:24:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:24: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:24: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:24: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:24: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:24: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:24: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:24:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:24:04 INFO - no previously-included directories found matching 'docs/_build' 09:24:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:24:04 INFO - Running setup.py install for pep8 09:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:24:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:24:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:24:04 INFO - no previously-included directories found matching 'docs/_build' 09:24:04 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:24:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:24:04 INFO - Cleaning up... 09:24:04 INFO - Return code: 0 09:24:04 INFO - Running post-action listener: _resource_record_post_action 09:24:04 INFO - Running post-action listener: _start_resource_monitoring 09:24:04 INFO - Starting resource monitoring. 09:24:04 INFO - ##### 09:24:04 INFO - ##### Running install step. 09:24:04 INFO - ##### 09:24:04 INFO - Running pre-action listener: _resource_record_pre_action 09:24:04 INFO - Running main action method: install 09:24:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:24:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:24:04 INFO - Reading from file tmpfile_stdout 09:24:04 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:24:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:24:05 INFO - Reading from file tmpfile_stdout 09:24:05 INFO - Output received: 09:24:05 INFO - Usage: mozinstall [options] installer 09:24:05 INFO - Options: 09:24:05 INFO - -h, --help show this help message and exit 09:24:05 INFO - -d DEST, --destination=DEST 09:24:05 INFO - Directory to install application into. [default: 09:24:05 INFO - "/builds/slave/test-pgo"] 09:24:05 INFO - --app=APP Application being installed. [default: firefox] 09:24:05 INFO - mkdir: /builds/slave/test-pgo/build/application 09:24:05 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:24:05 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:24:19 INFO - Reading from file tmpfile_stdout 09:24:19 INFO - Output received: 09:24:19 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:24:19 INFO - Running post-action listener: _resource_record_post_action 09:24:19 INFO - ##### 09:24:19 INFO - ##### Running run-tests step. 09:24:19 INFO - ##### 09:24:19 INFO - Running pre-action listener: _resource_record_pre_action 09:24:19 INFO - Running main action method: run_tests 09:24:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:24:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:24:19 INFO - Python 2.7.3 09:24:19 INFO - Return code: 0 09:24:19 INFO - grabbing minidump binary from tooltool 09:24: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:24: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 0x2352350>, '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 0x2353250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23536e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:24: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:24: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:24:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:19 INFO - Return code: 0 09:24:19 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:24:19 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:24:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:24:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:24:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:24:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:24:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:24:20 INFO - Return code: 0 09:24: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/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:24: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/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost 09:24:20 INFO - Using env: {'DISPLAY': ':0', 09:24:20 INFO - 'HOME': '/home/cltbld', 09:24:20 INFO - 'LANG': 'en_US.UTF-8', 09:24:20 INFO - 'LANGUAGE': 'en_US:en', 09:24:20 INFO - 'LOGNAME': 'cltbld', 09:24:20 INFO - 'MAIL': '/var/mail/cltbld', 09:24:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:24:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:20 INFO - 'MOZ_NO_REMOTE': '1', 09:24:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:24:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:20 INFO - 'NO_EM_RESTART': '1', 09:24: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:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:24:20 INFO - 'PWD': '/builds/slave/test-pgo', 09:24:20 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:24:20 INFO - 'SHELL': '/bin/bash', 09:24:20 INFO - 'SHLVL': '1', 09:24:20 INFO - 'TERM': 'linux', 09:24:20 INFO - 'TMOUT': '86400', 09:24:20 INFO - 'USER': 'cltbld', 09:24:20 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108', 09:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:20 INFO - '_': '/tools/buildbot/bin/python'} 09:24: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/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] with output_timeout 3600 09:24:20 INFO - mozversion INFO | application_buildid: 20160122065828 09:24:20 INFO - mozversion INFO | application_changeset: 8e913ace1e198c34423bfadc807296f6400078bd 09:24:20 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 09:24:20 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:20 INFO - mozversion INFO | application_name: Firefox 09:24:20 INFO - mozversion INFO | application_remotingname: firefox-dev 09:24:20 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:24:20 INFO - mozversion INFO | application_vendor: Mozilla 09:24:20 INFO - mozversion INFO | application_version: 45.0a2 09:24:20 INFO - mozversion INFO | platform_buildid: 20160122065828 09:24:20 INFO - mozversion INFO | platform_changeset: 8e913ace1e198c34423bfadc807296f6400078bd 09:24:20 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:24:20 INFO - mozversion INFO | platform_version: 45.0a2 09:24:20 INFO - 2016-01-22 09:24:20,907 DEBUG : using testdate: 1453483460 09:24:20 INFO - 2016-01-22 09:24:20,907 DEBUG : actual date: 1453483460 09:24:20 INFO - 2016-01-22 09:24:20,955 INFO : starting webserver on 'localhost:34243' 09:24:20 INFO - 2016-01-22 09:24:20,956 INFO : Starting test suite talos-linux64-ix-049 09:24:20 INFO - 2016-01-22 09:24:20,956 INFO : Starting test dromaeo_css 09:24:20 INFO - 2016-01-22 09:24:20,956 DEBUG : operating with platform_type : linux_ 09:24:20 INFO - 2016-01-22 09:24:20,957 INFO : Initialising browser for dromaeo_css test... 09:24:20 INFO - 2016-01-22 09:24:20,969 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdf1xNw/profile http://localhost:34243/getInfo.html 09:24:24 INFO - 2016-01-22 09:24:24,506 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:24:24 INFO - 2016-01-22 09:24:24,506 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:24:24 INFO - 2016-01-22 09:24:24,506 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:24:24 INFO - 2016-01-22 09:24:24,506 DEBUG : BROWSER_OUTPUT: __metrics 09:24:24 INFO - 2016-01-22 09:24:24,647 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:24:26 INFO - 2016-01-22 09:24:26,237 INFO : Browser initialized. 09:24:26 INFO - 2016-01-22 09:24:26,238 INFO : Running cycle 1/1 for dromaeo_css test... 09:24:26 INFO - 2016-01-22 09:24:26,238 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdf1xNw/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:24:37 INFO - 2016-01-22 09:24:37,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 137850880 09:24:37 INFO - 2016-01-22 09:24:37,990 DEBUG : BROWSER_OUTPUT: 09:25:29 INFO - 2016-01-22 09:25:29,625 DEBUG : BROWSER_OUTPUT: 1453483529622 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:27:36 INFO - 2016-01-22 09:27:36,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34243/tests/dromaeo/cssquery-ext.html) 09:27:37 INFO - 2016-01-22 09:27:37,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 255307776 09:27:37 INFO - 2016-01-22 09:27:37,827 DEBUG : BROWSER_OUTPUT: 09:30:39 INFO - 2016-01-22 09:30:39,974 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/dromaeo/cssquery-ext.html (next: http://localhost:34243/tests/dromaeo/cssquery-jquery.html) 09:30:40 INFO - 2016-01-22 09:30:40,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 224034816 09:30:40 INFO - 2016-01-22 09:30:40,539 DEBUG : BROWSER_OUTPUT: 09:33:42 INFO - 2016-01-22 09:33:42,385 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34243/tests/dromaeo/cssquery-mootools.html) 09:33:42 INFO - 2016-01-22 09:33:42,967 DEBUG : BROWSER_OUTPUT: RSS: Main: 213618688 09:33:42 INFO - 2016-01-22 09:33:42,967 DEBUG : BROWSER_OUTPUT: 09:36:44 INFO - 2016-01-22 09:36:44,742 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34243/tests/dromaeo/cssquery-prototype.html) 09:36:45 INFO - 2016-01-22 09:36:45,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 196517888 09:36:45 INFO - 2016-01-22 09:36:45,304 DEBUG : BROWSER_OUTPUT: 09:39:47 INFO - 2016-01-22 09:39:47,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34243/tests/dromaeo/cssquery-yui.html) 09:39:48 INFO - 2016-01-22 09:39:48,245 DEBUG : BROWSER_OUTPUT: RSS: Main: 204255232 09:39:48 INFO - 2016-01-22 09:39:48,245 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/dromaeo/cssquery-yui.html (next: http://localhost:34243/tests/dromaeo/cssquery-dojo.html) 09:42:50 INFO - 2016-01-22 09:42:50,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 215744512 09:42:50 INFO - 2016-01-22 09:42:50,786 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,786 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:42:50 INFO - 2016-01-22 09:42:50,787 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:42:50 INFO - 2016-01-22 09:42:50,787 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:42:50 INFO - 2016-01-22 09:42:50,787 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:42:50 INFO - 2016-01-22 09:42:50,787 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3227.112544050386;3285.4578994465915;3305.818180000099;3317.4028674838532;3331.33373325335;5341.626086173969;5355.357357117142;5356.517913387795;5362.999999999995;5366.168243922189;5356.625036247464;5380.973095134528;5382.6232163748555;5389.434109418514;5391.137418013119;4820.686655367407;4918.016396720653;4929.186684197112;4962.6774259673;4983.7508124593805;4915.238138088602;4962.975185124069;4980.128477516439;4986.202207646759;4987.9251811222675;4899.314096026559;4906.092372911023;4960.925586116211;4965.006998600276;4965.826196083138;4838.903221935541;4944.60443164546;4953.678010929296;4961.77672004757;4966.379202599675;4958.628102892298;4978.427480839678;5011.924821127686;5012.548870601663;5036.924446133311;5186;5236.162214045772;5249.370075590915;5250.816221432232;5291.656042357273;5812.854678633025;5899.32157801854;5901.970490147522;5919.733611987471;5951.821445356603;5884.970575147139;5895.941040589582;5897.970510147422;5900.43945825146;5902.380250073767;5370.650907690988;5405.7837686492885;5442.700651464168;5475.780968761244;5484.890302193974;5870.119482077637;5894.970525147432;5909.881802363929;5914.497267732291;5945.554083443672;5741.368449470555;5770.105633626795;5772.191893134965;5773.162891380726;5783.074708046693;5650.29371328584;5757.913631295534;5808.157817116495;5833.970830145822;5916.26046744157;5848.356680765112;5850.5027072699295;5856.882862342729;5857.677827719431;5883.588148829626;5837.124431335334;5842.824715258549;5847.181394604759;5848.093545500455;5849.707514624251;4312.396264522969;4378.408914796525;4417.249067658506;4515.5484451554585;4518.344839998182;4050.23045621331;4054.3107671695884;4062.654674352654;4065.410515475299;4089.1412803311637;5459.863503412367;5509.779608815603;5520.420355862657;5533.059379905426;5542.7782888684005;5593.944060559424;5704.657720536781;5758.049921762946;5843.29880414353;5843.620164689281;5514.062609356419;5518.503334699896;5530.778768849201;5536.031194540939;5540.63985840919;5121.078205922895;5133.897322053538;5135.255387968724;5141.100307446182;5171.276021357013;5659.094544872801;5815.767369305265;5973.820785376446;6025.216721826222;6034.9396506035255;5805.042168042226;5805.3614102447855;5808.273965754281;5809.680467574324;5811.302643682785;5910.408959104056;5941.37615550361;5953.732082056405;5963.403659634002;5964.970175149097;5471.835844924659;5635.577331700221;5640.435956404327;5741.16753070794;5744.368119506935;5811.302643682785;5837.854053648693;5849.5612829037145;5850.297964244318;5853.707314634336;5712.11462223356;5724;5724.3703192647945;5725.828225153252;5729.140628905698;5475.178723191553;5588.636738611977;5615.438456154352;5706.771729130955;5729.3984131665575;5599.468050535193;5662.8301150965535;5671.206031155724;5673.971630141823;5675.772969081356;5525.4474552544425;5735.483806457439;5750.511206547323;5771.278590176228;5775.075987842093;5521.1442226454965;5540.168974653834;5626.690532020778;5643.266375371176;5658.321001479849 09:42:50 INFO - 2016-01-22 09:42:50,787 DEBUG : BROWSER_OUTPUT: |1;ext.html;585.6573904266004;626.2766504687087;631.2992578238157;637.5059329020012;639.076534407781;39653.2069358613;40807.79596102019;40853.182936341254;40917.59082409175;40994.795026024825;44987.325190122174;45615.31577026347;45635.54364456354;45646.08707825842;45671;8091.838163236732;8204.958975205145;9058;9305.395149315307;10476.371417714961;10697.09074728649;10822.72943176423;10833.079188269006;10839.83740243897;10843.620473283436;10830.458477076114;10929.945350273278;10952.561897524089;10968.23222374434;10968.670939871816;8352.12302708216;8585.699500517452;10801.135909127252;11051.778964420666;11068;10658.786824263549;10820.837687434694;10821.729456763565;10822.729431764268;10824.40465774382;41516.79241603773;42084.78957605223;42108.1578368434;42168.57831421677;42186.78906605478;3392.270661807723;3464.9307013859834;3639.253952939641;3657.1771351445977;3678.264347130558;31618.683813162035;32145.6785432145;32356.514652280235;32405.189870253198;35227.82386088053;4189.7695626740515;4239.660827133822;4274.316109422509;4326.978365108186;4370.344448332744;274293;276118;276725;277011;277046;62727.37272627215;63404;64089;64159.35840641627;64240.35759642437;76624.61687691526;78007.60996195096;78134.609326953;78190.6090469544;78250.60874695703;17313.134343282785;17453.516451101546;17460.214290356966;17470.47588572345;17643.029633370286;13101.213927164401;13204.537841175512;13218.867811321956;13230.272335021666;13233.073684842162;21471.14115435396;21819.67270490944;21917.561648767256;21958.89020554919;22032.44918877009;8461.40770146084;8503.14968503157;8563.828723425497;8654.480731156025;8732.345074119467;1093.6609651008214;1141.2923987128033;1147.1568397228032;1156.150229581257;1163.9825402618967;29410.705892940798;29462.11613651594;30116.69883301183;30478.847605762272;30763.38473230523;1055.1611468882256;1079.2121751121567;1095.4960718069617;1168.1764356128908;1233.0505510756668;12827.486900524062;13208.471661133448;13274.070815042855;13330;15179.772303415457;438631;438942;442994;443729;443860;53787.462125378246;53976.190357144675;54080.72959635177;54404.183937240974;54449.1832622511;1487.1820498725658;1498.48302335694;1498.7751837224284;1501.4444655477325;1507.3367718203956;26241.475170496866;27284;27920.58119128174;28511.572326414705;29612;52738;53299.46700532945;53342.99999999845;53397.73301133625;53409.46590534045;311069;311574;312723;312788;313430;28299.717002829708;33497.49753753647;33559.83220083982;33635.66364336325;33770.66229337675;37249;37727.81136094302;38102.047448814;38211.80894095512;38668.03329916662;33079.66920330862;33212.50181247235;33278.5008224872;33354.83322583372;33511.16222094467;29130.27174320659;30646;33638.663613363555;34270.828645856614;36328.45507317445;33040.66959330472;33189.668103318654;33562;33716.662833372335;33774;28076.85961570179;30247.395052099277;31603.525947111273;32559.511607325436;32652;22350.21774237894;22441.10235590558;22543.323700289016;22608.886955565118;22779.772202277763 09:42:50 INFO - 2016-01-22 09:42:50,788 DEBUG : BROWSER_OUTPUT: |2;jquery.html;389.2545774841176;399.2075729676593;400.5773908526505;401.44400005991713;401.8854626431467;14387.928060359684;17699.203535840887;18069.638607227847;18089.1859866306;19855.5036124097;17322.56693582661;17383.565410864736;18181.090945452706;18210.45368638943;18243.270269189215;6180.412860778219;6279.120923070751;6291.3708629136945;6299.275583307909;6321.77873774418;6117.69411529424;6222.751089956397;6229.6573688447115;6248.968755156217;6277.0584412338285;4465.218586747323;4585.380973568575;6217.191765070557;6221.844453888666;6222;6661.70022348993;6839.179298484214;6889.138857642794;6899.206591241996;6903.344182302673;5132.307138536287;5281.181416880389;5285.286486324374;5285.577153827685;6607.702653380609;13357.553859896912;13579.049466537346;21687.891560542095;22371.664425033636;22404.66393004106;2220.8667479951255;2265.456290490287;2329.685492458513;2392.09100541793;2472.938176545583;10177;10369.79260414795;10581.682549523526;12582.68543286416;13465.259410732406;1919.6256729937668;1972.4575741671013;1983.1968052938582;2013.1142297389101;2017.3040301096098;67012.32987670158;67526;68090;68150.65924670345;68248.3175168247;10978.560857565768;11035.172362072877;11155.05182059534;11212.55149793999;11218.719532011602;14907.329170187368;14919.881008924374;14925.029873058435;14925.477608283874;14938.32777525014;8261.463004904783;8308.12764659714;8442.62008209632;8494.193051660082;8500;8072;8091.959540202261;8169.30560902318;8169.877451838227;8201.056878458934;4752.049590081998;4773.665843390999;4787.8563643090765;4850.6362022848425;4894.706317620954;1786.916938402713;1836.540864783804;1889.3576184097474;1918.8656794024284;1939.93210237641;913.7167478081683;978.3445091788519;1040.6877936618985;1042.9883013476915;1087.842262871895;10451.529681164366;17320.566985825455;17540;17748.55628609295;18066.90966545159;495.68028621538906;497.8133200049981;499.6402590135097;517.4541651590757;529.5366554265019;6362.713677884506;6502;6635.701393437307;6699.263081061079;6710.966445167743;589224;590254;598870;604518;607225;85325;85823.57088214644;86471.56764216139;87226;88069;543.4293991309189;544.2978557660653;545.7598656591088;545.7707762739659;546.5136028934252;11134.164937629872;11295.096392288433;11354.602588909349;11354.772904541698;11378.829317560077;37273.068173295884;37920.81039594785;38946.803541793364;39001.41497877591;40128.197436050534;46967.76516117398;47400;47917;49370.753146235475;49769.25346119739;9728.416295022322;9796.118349348593;10063.094321511098;10077.496125193858;10139.7465063374;11883.702907427383;11919.106067044833;11935.52257909674;12022.158448908493;14906.850931490546;14666.26668666541;15151.621209469851;15204.923975380052;15207.467738629095;15375.61560960985;14727.263636817903;14914.477993270184;14924.403023879358;15449.459268925533;15662.216889155725;10131.189504839447;10261.2304077196;10360.740981475223;11071.944640276746;11128.221024528206;16241.269142888363;16481.17594120265;16695.916520417806;16755.1622418881;16818;17050.232739526502;17051.829481705023;17383.130843458028;17647.911760441115;17913.820861791217 09:42:50 INFO - 2016-01-22 09:42:50,789 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1354.4514471638977;1547.4042493639952;1550.3178601415377;1809.7285407188922;1846.9907650461741;6882.6558672066385;8029.879551806727;8213;8222.629981650825;8230.218129277724;7767.495112817663;7927.841443171147;7935.127136015034;7942.007249093864;7944.245296696819;1986.920523179071;2026.3819535041807;2026.898655067248;2249.460129568908;2281.121768119278;2261.649444336122;2322.1175953137786;2322.3729593009875;2325.5697695926306;2326.1044497868356;2270.6594010898334;2305.3199306204624;2326.127702111708;2329.126577533425;2330.5455436189864;2281.2015794471968;2291.7822806833324;2316.085146367184;2320.6751054852334;2344.6834677318516;2289.713785776778;2312.0405031911737;2317.7566355532763;2319.4549280918973;2328.871912044837;8226.136255693125;8288.046874609376;8317.459365141247;8323.167683231628;8330.541820199887;4702.5297470253045;4781.402324709411;4789.137955168068;4792.520747925215;4796.808127674889;2981.2844917219927;3030.1818509002665;3063.4638938185726;3069.109958112145;3095.5511450839726;989.6734077754396;995.875515560555;1023.9078482936571;1044.650042235852;1048.5176818663424;10971.286866353661;11100.167487438393;11115.888841111648;11117.388543630097;11149;4192.979035104805;4220.177065472203;4253.149370125925;4299.032717638569;4341.544137865541;9336.11306925841;9356.06439356059;9356.766080848034;9394.624215031323;9410.576524056434;4844.394450693663;4863.3434486344595;4868.513148685103;4895.632827537949;4904.362432883702;4472.306792447176;4530.773461326987;4533.433320834895;4534.77326133692;4541.6593755468475;3232.82219477931;3257.4625186843905;3355.681210285019;3386.8306584670668;3528.400171970771;1103.61925135828;1122.2649164797072;1126.1385040444068;1131.2816361610437;1135.5514571744116;1060.1095080132725;1062.9574817007388;1136.4545018391218;1277.8722127787332;1402.8737413632618;7671.232876712395;7683.731069412655;7712.344450721752;7758.612069396508;7920.881186782203;635.6313338263795;650.5153660522941;674.5345711459175;682.4028974647184;790.774629230678;6139.324674285825;6232.532560058014;6234.470070044006;6240.126382306481;6245.594036387621;9385.765355866159;9400.764980875532;9525.428474291564;9530.666426675032;9648.228141748643;9414.19979301767;9470.479123648265;9564.187044101329;9574.186194173433;9580.95209523948;7383.778486645409;7567.470277080662;7615.504992175491;7654.119776225694;7658.69365225407;7043.225245222919;7049.859002820018;7067.929320706727;7081.00865878767;7092.858142837217;8407.327413806759;8442.366822488215;8497.192766687154;8612.18184272493;8634.5251011195;8558.358123140664;8625.525596092275;8754.73735787902;8766;8771.692990745294;6895.103636527219;7114.395276401357;7126.465515086285;7139.286071392819;7143.1428228612895;6391.488680905423;6480.449161821298;6480.870382592416;7613.314801667876;7688.538687678757;7371.8157046074275;7519.172890982097;7519.285667861544;7531.096268447822;7546.773596792105;7317.3780228679025;7334.266573342837;7401.888971665537;7456.142543607554;7500.287472690085;7275.272472752894;7288.453365997465;7320.560766354036;7377.889331660137;7426.665800038903;6374;6398.776042838665;6401.967990160019;6410.839729006812;6414.839629009312;6419.35806419373;6871.381575658214;6898.931010689829;6899.517033807585;6903.6203008835 09:42:50 INFO - 2016-01-22 09:42:50,791 DEBUG : BROWSER_OUTPUT: |4;prototype.html;362.77145398399006;365.57410616631626;370.172664095746;374.10028880542296;376.3846111607523;5875.353711091781;5883.470487656115;5901.645901245917;5902.49828764555;5970.611910225833;5826.563007774424;5839.5620328475325;5840.299164100304;5843.824685259438;5846.88306233875;4629;4635.490096089427;4636.09596128755;4638.721676699409;4643.8142474300985;4527.615152712024;4646;4669.649776266777;4669.789859456333;4691.648126390517;4603.5166307537565;4609.377734005917;4611.054733779567;4613.146567884935;4623.491415944243;4401.185780630562;4502.5047244803045;4608.562186592285;4610.239310513761;4612.746298953573;3988.840446382141;4041.211963667086;4052.6960477963976;4052.817623206933;4057.6145266199665;5688.374278829326;5689.231953686241;5695.515881150066;5708.71456427177;5717;5133.84598462043;5242.895142097175;5267.26258323835;5367.785288588452;5374.91937620936;6306.054091886208;6315.463185629227;6316.463100636452;6320.273168585625;6322.683865806691;4514.164879497303;4521.954780452219;4524.61540769035;4527.071950250223;4529.162105010583;6375.139356186902;6472.3204063573585;6548.377904099103;6553.475721942234;6555.541112122153;4593.678442508993;4616.976915115403;4618.399608050931;4620.653450991189;4624.606908412756;4669.41632295963;4675.742834144155;4678.485366609671;4679.1577516046755;4680.695754775997;5331.040412725747;5348.545373643284;5356.91964620531;5357.116075847442;5358.142697168434;6251.78118765841;6326.9683651581445;6353.745850166034;6355.523335749837;6364.236291645032;1626.4470080172798;1631.6247263129546;1642.89321194122;1644.4819881737214;1645.2596331650566;1116.8883111688929;1120.7534342444649;1125.3697929159696;1130.8360287758394;1136.0003197186588;6142.539309551801;6147.815565533041;6149.877002459926;6152.907706384408;6156.322804491502;6471.611703297723;6560;6566.638834864033;6566.835829104215;6571.901421478682;6014.127951447016;6128.846778830565;6137.877242455215;6140.723667434976;6144.539159563051;3521.330947120039;3544.592371877267;3544.6278140794843;3550.982245088758;3551.5027896094566;6717.059611654372;6729.25978142404;6729.966350168218;6745.932540674628;6752.5948443093575;6459.224893012869;6603.405693487609;6666.100076489612;6690.59856408617;6693.497987650849;6613.966930165415;6768.2216545097945;6782.898256526156;6787.5588086774205;6799.898001529981;6592.604443733391;6605.5376123670885;6611.504137189634;6614.040964060088;6616.206055273398;6371.012493063583;6499.440134367623;6533.216014078341;6535.542512024303;6537.953927371597;6773.729050838108;6784.728610855511;6785.9660701696175;6786.796396108125;6790.117284752951;4583.152116858392;4585.977070114628;4587.541245875385;4589.58693717567;4591.609713174284;6467.126937863516;6608.074869518367;6611.272759996493;6615.4046135848;6625.602463852184;6447.774327898501;6458.22501299847;6463.967680161569;6468;6479.060536222316;6431.678416079271;6450.290468048417;6453.9354606453335;6456.386643268882;6462.4183823456115;6241.126242325987;6312.842178945563;6346;6348.079528468438;6360.77737279193;6454.838629034311;6461.483081353386;6463.967680161569;6466.51501137426;6469.935300647122;6258.217722784652;6275.152854364603;6277.12120303167;6283.685815709105;6283.96858015707 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: |5;yui.html;803.6022169026332;806.3191530662525;835.3567752830321;911.8221946720383;928.0302084321883;12747.490100395984;12926.224426534414;12940;12972.221666700005;12973.091883568153;11897.583584574542;11986.101042421831;12014;12016;12044.69888252794;738.8079099434159;747.1184002876604;751.4439314906984;897.9146981036791;900.0234745301356;907.0204179486173;909.9954500227523;914.236612428623;915.1077699243251;916.1617120334886;957.0477375011873;962.06679520865;962.4610218277752;963.2294164668273;963.36899330938;771.4368510986983;782.5656760497922;932.365991126034;953.7997020625677;958.2621381536213;707.734599525178;709.0286307758353;709.1667290933133;709.6345382128208;710.0201721624126;12287.201331913397;12604.873951260462;12606.306653134074;12607.243565386014;12608.495660173581;11694.707632309175;12130.696732581757;12708.237505749685;13006.479740810357;13178.406971686298;10850.782984340349;11160.16298777595;11198.776024479464;11270.774584508346;11462.48418821155;3819.1216020315483;3821.8662346817728;3858.305505009097;3874.3219936511277;3876.7092468064725;46245.30632040522;46387.536124638995;46964.7651761739;47329.76335118302;49407.752961235325;9456.763580910532;9571.569279382444;10344.586216551405;10450.68647940563;10484.528196231187;16219.837801622069;19226;19378.515537111685;19438.22247110128;19466.90266548658;7934.127246002935;7950;8006.359491240687;8124.309433698084;8158.388120890957;4420.248557745191;4438.1567502174485;4469.217886869785;4500;4523.864284071417;3256.9348613027605;3280.1471617379652;3283.7044665980175;3286.1291757684235;3288.93422131556;584.2288179602969;584.4068270705153;598.7545106506311;599.5054080383701;604.921360223177;562.5583916625429;661.5071771530145;670.8825955457776;695.8817001109824;740.8036870229394;8607.440516366416;8768.912310876936;8774.429662071945;8919.821603567892;9003.36976411658;509.8241106818142;551.6286886461355;570.5521165884843;638.5944924972583;712.803978905805;3946.2896678597554;4030.7380020298588;4033.798310084484;4055.3916912463364;4101.815418306183;57782.13326800102;58201.12698309529;58524.707376462844;58547;58560;51523.742381288605;51595.484045159064;51794.48205517972;52122.73938630358;52271;46336.304955425694;47003.76498117556;47407.28889066667;48536.27195592069;48640.27039594409;7630.1606823248285;7669.884951725617;7943.44395892282;8020.879686804586;8054.31538319226;35114.29771404507;35366.82316588401;35798.82100589584;35882.64117358898;36245;43197.78401107974;43230.783846080565;45927.311090333;47170.76414617905;47190.76404617955;9869.210463163074;10163.94918025405;10205.897941020496;10836.729081773019;10872.836907446554;12869.935650321688;12929.612111636667;13152.079354445097;13278.734425311633;13901.791473128114;11426.744656513161;11504.654860354203;11637.476313565741;11959.521619135137;12069.456874440846;11599.76800464003;11681.217285937199;11710.355930423908;11780.587679431179;12559.686007849878;9353.953230233805;11067.501962411872;11684.18210725241;11865.347405892759;12136.878631213927;9978.501074946369;10061.849072264069;10154.441505717257;10331.896681033093;10461.110805581611;9789.559469823733;9821.95089024579;9920.503974801375;10077.647282345082;10196.949015254877 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,902 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: __startTimestamp1453484570784__endTimestamp 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:42:50 INFO - 2016-01-22 09:42:50,792 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5403.51 Median:5599.47 stddev:586.64 (10.5%) stddev-sans-first:563.05 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: Values: 3227.1 3285.5 3305.8 3317.4 3331.3 5341.6 5355.4 5356.5 5363.0 5366.2 5356.6 5381.0 5382.6 5389.4 5391.1 4820.7 4918.0 4929.2 4962.7 4983.8 4915.2 4963.0 4980.1 4986.2 4987.9 4899.3 4906.1 4960.9 4965.0 4965.8 4838.9 4944.6 4953.7 4961.8 4966.4 4958.6 4978.4 5011.9 5012.5 5036.9 5186.0 5236.2 5249.4 5250.8 5291.7 5812.9 5899.3 5902.0 5919.7 5951.8 5885.0 5895.9 5898.0 5900.4 5902.4 5370.7 5405.8 5442.7 5475.8 5484.9 5870.1 5895.0 5909.9 5914.5 5945.6 5741.4 5770.1 5772.2 5773.2 5783.1 5650.3 5757.9 5808.2 5834.0 5916.3 5848.4 5850.5 5856.9 5857.7 5883.6 5837.1 5842.8 5847.2 5848.1 5849.7 4312.4 4378.4 4417.2 4515.5 4518.3 4050.2 4054.3 4062.7 4065.4 4089.1 5459.9 5509.8 5520.4 5533.1 5542.8 5593.9 5704.7 5758.0 5843.3 5843.6 5514.1 5518.5 5530.8 5536.0 5540.6 5121.1 5133.9 5135.3 5141.1 5171.3 5659.1 5815.8 5973.8 6025.2 6034.9 5805.0 5805.4 5808.3 5809.7 5811.3 5910.4 5941.4 5953.7 5963.4 5965.0 5471.8 5635.6 5640.4 5741.2 5744.4 5811.3 5837.9 5849.6 5850.3 5853.7 5712.1 5724.0 5724.4 5725.8 5729.1 5475.2 5588.6 5615.4 5706.8 5729.4 5599.5 5662.8 5671.2 5674.0 5675.8 5525.4 5735.5 5750.5 5771.3 5775.1 5521.1 5540.2 5626.7 5643.3 5658.3 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51669.11 Median:32846.33 stddev:92452.23 (281.5%) stddev-sans-first:92632.02 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: Values: 585.7 626.3 631.3 637.5 639.1 39653.2 40807.8 40853.2 40917.6 40994.8 44987.3 45615.3 45635.5 45646.1 45671.0 8091.8 8205.0 9058.0 9305.4 10476.4 10697.1 10822.7 10833.1 10839.8 10843.6 10830.5 10929.9 10952.6 10968.2 10968.7 8352.1 8585.7 10801.1 11051.8 11068.0 10658.8 10820.8 10821.7 10822.7 10824.4 41516.8 42084.8 42108.2 42168.6 42186.8 3392.3 3464.9 3639.3 3657.2 3678.3 31618.7 32145.7 32356.5 32405.2 35227.8 4189.8 4239.7 4274.3 4327.0 4370.3 274293.0 276118.0 276725.0 277011.0 277046.0 62727.4 63404.0 64089.0 64159.4 64240.4 76624.6 78007.6 78134.6 78190.6 78250.6 17313.1 17453.5 17460.2 17470.5 17643.0 13101.2 13204.5 13218.9 13230.3 13233.1 21471.1 21819.7 21917.6 21958.9 22032.4 8461.4 8503.1 8563.8 8654.5 8732.3 1093.7 1141.3 1147.2 1156.2 1164.0 29410.7 29462.1 30116.7 30478.8 30763.4 1055.2 1079.2 1095.5 1168.2 1233.1 12827.5 13208.5 13274.1 13330.0 15179.8 438631.0 438942.0 442994.0 443729.0 443860.0 53787.5 53976.2 54080.7 54404.2 54449.2 1487.2 1498.5 1498.8 1501.4 1507.3 26241.5 27284.0 27920.6 28511.6 29612.0 52738.0 53299.5 53343.0 53397.7 53409.5 311069.0 311574.0 312723.0 312788.0 313430.0 28299.7 33497.5 33559.8 33635.7 33770.7 37249.0 37727.8 38102.0 38211.8 38668.0 33079.7 33212.5 33278.5 33354.8 33511.2 29130.3 30646.0 33638.7 34270.8 36328.5 33040.7 33189.7 33562.0 33716.7 33774.0 28076.9 30247.4 31603.5 32559.5 32652.0 22350.2 22441.1 22543.3 22608.9 22779.8 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,793 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31190.45 Median:19873.00 stddev:97778.38 (492.0%) stddev-sans-first:98025.32 09:42:50 INFO - 2016-01-22 09:42:50,794 DEBUG : BROWSER_OUTPUT: Values: 389.3 399.2 400.6 401.4 401.9 14387.9 17699.2 18069.6 18089.2 19855.5 17322.6 17383.6 18181.1 18210.5 18243.3 6180.4 6279.1 6291.4 6299.3 6321.8 6117.7 6222.8 6229.7 6249.0 6277.1 4465.2 4585.4 6217.2 6221.8 6222.0 6661.7 6839.2 6889.1 6899.2 6903.3 5132.3 5281.2 5285.3 5285.6 6607.7 13357.6 13579.0 21687.9 22371.7 22404.7 2220.9 2265.5 2329.7 2392.1 2472.9 10177.0 10369.8 10581.7 12582.7 13465.3 1919.6 1972.5 1983.2 2013.1 2017.3 67012.3 67526.0 68090.0 68150.7 68248.3 10978.6 11035.2 11155.1 11212.6 11218.7 14907.3 14919.9 14925.0 14925.5 14938.3 8261.5 8308.1 8442.6 8494.2 8500.0 8072.0 8092.0 8169.3 8169.9 8201.1 4752.0 4773.7 4787.9 4850.6 4894.7 1786.9 1836.5 1889.4 1918.9 1939.9 913.7 978.3 1040.7 1043.0 1087.8 10451.5 17320.6 17540.0 17748.6 18066.9 495.7 497.8 499.6 517.5 529.5 6362.7 6502.0 6635.7 6699.3 6711.0 589224.0 590254.0 598870.0 604518.0 607225.0 85325.0 85823.6 86471.6 87226.0 88069.0 543.4 544.3 545.8 545.8 546.5 11134.2 11295.1 11354.6 11354.8 11378.8 37273.1 37920.8 38946.8 39001.4 40128.2 46967.8 47400.0 47917.0 49370.8 49769.3 9728.4 9796.1 10063.1 10077.5 10139.7 11883.7 11919.1 11935.5 12022.2 14906.9 14666.3 15151.6 15204.9 15207.5 15375.6 14727.3 14914.5 14924.4 15449.5 15662.2 10131.2 10261.2 10360.7 11071.9 11128.2 16241.3 16481.2 16695.9 16755.2 16818.0 17050.2 17051.8 17383.1 17647.9 17913.8 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5575.20 Median:6449.90 stddev:2944.17 (45.6%) stddev-sans-first:2935.34 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: Values: 1354.5 1547.4 1550.3 1809.7 1847.0 6882.7 8029.9 8213.0 8222.6 8230.2 7767.5 7927.8 7935.1 7942.0 7944.2 1986.9 2026.4 2026.9 2249.5 2281.1 2261.6 2322.1 2322.4 2325.6 2326.1 2270.7 2305.3 2326.1 2329.1 2330.5 2281.2 2291.8 2316.1 2320.7 2344.7 2289.7 2312.0 2317.8 2319.5 2328.9 8226.1 8288.0 8317.5 8323.2 8330.5 4702.5 4781.4 4789.1 4792.5 4796.8 2981.3 3030.2 3063.5 3069.1 3095.6 989.7 995.9 1023.9 1044.7 1048.5 10971.3 11100.2 11115.9 11117.4 11149.0 4193.0 4220.2 4253.1 4299.0 4341.5 9336.1 9356.1 9356.8 9394.6 9410.6 4844.4 4863.3 4868.5 4895.6 4904.4 4472.3 4530.8 4533.4 4534.8 4541.7 3232.8 3257.5 3355.7 3386.8 3528.4 1103.6 1122.3 1126.1 1131.3 1135.6 1060.1 1063.0 1136.5 1277.9 1402.9 7671.2 7683.7 7712.3 7758.6 7920.9 635.6 650.5 674.5 682.4 790.8 6139.3 6232.5 6234.5 6240.1 6245.6 9385.8 9400.8 9525.4 9530.7 9648.2 9414.2 9470.5 9564.2 9574.2 9581.0 7383.8 7567.5 7615.5 7654.1 7658.7 7043.2 7049.9 7067.9 7081.0 7092.9 8407.3 8442.4 8497.2 8612.2 8634.5 8558.4 8625.5 8754.7 8766.0 8771.7 6895.1 7114.4 7126.5 7139.3 7143.1 6391.5 6480.4 6480.9 7613.3 7688.5 7371.8 7519.2 7519.3 7531.1 7546.8 7317.4 7334.3 7401.9 7456.1 7500.3 7275.3 7288.5 7320.6 7377.9 7426.7 6374.0 6398.8 6402.0 6410.8 6414.8 6419.4 6871.4 6898.9 6899.5 6903.6 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5337.29 Median:6071.49 stddev:1585.01 (26.1%) stddev-sans-first:1544.85 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: Values: 362.8 365.6 370.2 374.1 376.4 5875.4 5883.5 5901.6 5902.5 5970.6 5826.6 5839.6 5840.3 5843.8 5846.9 4629.0 4635.5 4636.1 4638.7 4643.8 4527.6 4646.0 4669.6 4669.8 4691.6 4603.5 4609.4 4611.1 4613.1 4623.5 4401.2 4502.5 4608.6 4610.2 4612.7 3988.8 4041.2 4052.7 4052.8 4057.6 5688.4 5689.2 5695.5 5708.7 5717.0 5133.8 5242.9 5267.3 5367.8 5374.9 6306.1 6315.5 6316.5 6320.3 6322.7 4514.2 4522.0 4524.6 4527.1 4529.2 6375.1 6472.3 6548.4 6553.5 6555.5 4593.7 4617.0 4618.4 4620.7 4624.6 4669.4 4675.7 4678.5 4679.2 4680.7 5331.0 5348.5 5356.9 5357.1 5358.1 6251.8 6327.0 6353.7 6355.5 6364.2 1626.4 1631.6 1642.9 1644.5 1645.3 1116.9 1120.8 1125.4 1130.8 1136.0 6142.5 6147.8 6149.9 6152.9 6156.3 6471.6 6560.0 6566.6 6566.8 6571.9 6014.1 6128.8 6137.9 6140.7 6144.5 3521.3 3544.6 3544.6 3551.0 3551.5 6717.1 6729.3 6730.0 6745.9 6752.6 6459.2 6603.4 6666.1 6690.6 6693.5 6614.0 6768.2 6782.9 6787.6 6799.9 6592.6 6605.5 6611.5 6614.0 6616.2 6371.0 6499.4 6533.2 6535.5 6538.0 6773.7 6784.7 6786.0 6786.8 6790.1 4583.2 4586.0 4587.5 4589.6 4591.6 6467.1 6608.1 6611.3 6615.4 6625.6 6447.8 6458.2 6464.0 6468.0 6479.1 6431.7 6450.3 6453.9 6456.4 6462.4 6241.1 6312.8 6346.0 6348.1 6360.8 6454.8 6461.5 6464.0 6466.5 6469.9 6258.2 6275.2 6277.1 6283.7 6284.0 09:42:50 INFO - 2016-01-22 09:42:50,795 DEBUG : BROWSER_OUTPUT: 09:42:50 INFO - 2016-01-22 09:42:50,796 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13943.35 Median:46290.81 stddev:16112.39 (34.8%) stddev-sans-first:16127.37 09:42:50 INFO - 2016-01-22 09:42:50,796 DEBUG : BROWSER_OUTPUT: Values: 803.6 806.3 835.4 911.8 928.0 12747.5 12926.2 12940.0 12972.2 12973.1 11897.6 11986.1 12014.0 12016.0 12044.7 738.8 747.1 751.4 897.9 900.0 907.0 910.0 914.2 915.1 916.2 957.0 962.1 962.5 963.2 963.4 771.4 782.6 932.4 953.8 958.3 707.7 709.0 709.2 709.6 710.0 12287.2 12604.9 12606.3 12607.2 12608.5 11694.7 12130.7 12708.2 13006.5 13178.4 10850.8 11160.2 11198.8 11270.8 11462.5 3819.1 3821.9 3858.3 3874.3 3876.7 46245.3 46387.5 46964.8 47329.8 49407.8 9456.8 9571.6 10344.6 10450.7 10484.5 16219.8 19226.0 19378.5 19438.2 19466.9 7934.1 7950.0 8006.4 8124.3 8158.4 4420.2 4438.2 4469.2 4500.0 4523.9 3256.9 3280.1 3283.7 3286.1 3288.9 584.2 584.4 598.8 599.5 604.9 562.6 661.5 670.9 695.9 740.8 8607.4 8768.9 8774.4 8919.8 9003.4 509.8 551.6 570.6 638.6 712.8 3946.3 4030.7 4033.8 4055.4 4101.8 57782.1 58201.1 58524.7 58547.0 58560.0 51523.7 51595.5 51794.5 52122.7 52271.0 46336.3 47003.8 47407.3 48536.3 48640.3 7630.2 7669.9 7943.4 8020.9 8054.3 35114.3 35366.8 35798.8 35882.6 36245.0 43197.8 43230.8 45927.3 47170.8 47190.8 9869.2 10163.9 10205.9 10836.7 10872.8 12869.9 12929.6 13152.1 13278.7 13901.8 11426.7 11504.7 11637.5 11959.5 12069.5 11599.8 11681.2 11710.4 11780.6 12559.7 9354.0 11067.5 11684.2 11865.3 12136.9 9978.5 10061.8 10154.4 10331.9 10461.1 9789.6 9822.0 9920.5 10077.6 10196.9 09:42:50 INFO - 2016-01-22 09:42:50,797 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:42:50 INFO - 2016-01-22 09:42:50,797 DEBUG : BROWSER_OUTPUT: 09:42:51 INFO - 2016-01-22 09:42:51,426 INFO : Browser exited with error code: 0 09:42:51 INFO - 2016-01-22 09:42:51,442 INFO : Completed test dromaeo_css (00:18:30) 09:42:51 INFO - 2016-01-22 09:42:51,443 INFO : Starting test kraken 09:42:51 INFO - 2016-01-22 09:42:51,443 DEBUG : operating with platform_type : linux_ 09:42:51 INFO - 2016-01-22 09:42:51,443 INFO : Initialising browser for kraken test... 09:42:51 INFO - 2016-01-22 09:42:51,449 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfwCEqL/profile http://localhost:34243/getInfo.html 09:42:55 INFO - 2016-01-22 09:42:55,211 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:42:55 INFO - 2016-01-22 09:42:55,211 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:42:55 INFO - 2016-01-22 09:42:55,211 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:42:55 INFO - 2016-01-22 09:42:55,211 DEBUG : BROWSER_OUTPUT: __metrics 09:42:55 INFO - 2016-01-22 09:42:55,348 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:42:56 INFO - 2016-01-22 09:42:56,169 INFO : Browser initialized. 09:42:56 INFO - 2016-01-22 09:42:56,169 INFO : Running cycle 1/1 for kraken test... 09:42:56 INFO - 2016-01-22 09:42:56,169 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfwCEqL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:43:07 INFO - 2016-01-22 09:43:07,976 DEBUG : BROWSER_OUTPUT: RSS: Main: 137957376 09:43:07 INFO - 2016-01-22 09:43:07,977 DEBUG : BROWSER_OUTPUT: 09:43:10 INFO - 2016-01-22 09:43:10,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34243/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:10 INFO - 2016-01-22 09:43:10,973 DEBUG : BROWSER_OUTPUT: RSS: Main: 170065920 09:43:10 INFO - 2016-01-22 09:43:10,973 DEBUG : BROWSER_OUTPUT: 09:43:13 INFO - 2016-01-22 09:43:13,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34243/tests/kraken/driver.html?testName=audio-dft) 09:43:14 INFO - 2016-01-22 09:43:14,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 167559168 09:43:14 INFO - 2016-01-22 09:43:14,096 DEBUG : BROWSER_OUTPUT: 09:43:16 INFO - 2016-01-22 09:43:16,913 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34243/tests/kraken/driver.html?testName=audio-fft) 09:43:17 INFO - 2016-01-22 09:43:17,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 164794368 09:43:17 INFO - 2016-01-22 09:43:17,495 DEBUG : BROWSER_OUTPUT: 09:43:19 INFO - 2016-01-22 09:43:19,467 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34243/tests/kraken/driver.html?testName=audio-oscillator) 09:43:20 INFO - 2016-01-22 09:43:20,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 165175296 09:43:20 INFO - 2016-01-22 09:43:20,029 DEBUG : BROWSER_OUTPUT: 09:43:22 INFO - 2016-01-22 09:43:22,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:43:22 INFO - 2016-01-22 09:43:22,794 DEBUG : BROWSER_OUTPUT: RSS: Main: 163176448 09:43:22 INFO - 2016-01-22 09:43:22,794 DEBUG : BROWSER_OUTPUT: 09:43:25 INFO - 2016-01-22 09:43:25,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34243/tests/kraken/driver.html?testName=imaging-darkroom) 09:43:26 INFO - 2016-01-22 09:43:26,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 164151296 09:43:26 INFO - 2016-01-22 09:43:26,220 DEBUG : BROWSER_OUTPUT: 09:43:29 INFO - 2016-01-22 09:43:29,553 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34243/tests/kraken/driver.html?testName=imaging-desaturate) 09:43:30 INFO - 2016-01-22 09:43:30,120 DEBUG : BROWSER_OUTPUT: RSS: Main: 164818944 09:43:30 INFO - 2016-01-22 09:43:30,120 DEBUG : BROWSER_OUTPUT: 09:43:32 INFO - 2016-01-22 09:43:32,817 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34243/tests/kraken/driver.html?testName=json-parse-financial) 09:43:33 INFO - 2016-01-22 09:43:33,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 165085184 09:43:33 INFO - 2016-01-22 09:43:33,387 DEBUG : BROWSER_OUTPUT: 09:43:34 INFO - 2016-01-22 09:43:34,981 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:43:35 INFO - 2016-01-22 09:43:35,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 169635840 09:43:35 INFO - 2016-01-22 09:43:35,542 DEBUG : BROWSER_OUTPUT: 09:43:37 INFO - 2016-01-22 09:43:37,284 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:43:37 INFO - 2016-01-22 09:43:37,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 166203392 09:43:37 INFO - 2016-01-22 09:43:37,875 DEBUG : BROWSER_OUTPUT: 09:43:39 INFO - 2016-01-22 09:43:39,860 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:43:40 INFO - 2016-01-22 09:43:40,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 176353280 09:43:40 INFO - 2016-01-22 09:43:40,433 DEBUG : BROWSER_OUTPUT: 09:43:42 INFO - 2016-01-22 09:43:42,755 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:43:43 INFO - 2016-01-22 09:43:43,337 DEBUG : BROWSER_OUTPUT: RSS: Main: 184344576 09:43:43 INFO - 2016-01-22 09:43:43,337 DEBUG : BROWSER_OUTPUT: 09:43:45 INFO - 2016-01-22 09:43:45,844 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:43:46 INFO - 2016-01-22 09:43:46,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 183472128 09:43:46 INFO - 2016-01-22 09:43:46,418 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,088 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34243/tests/kraken/driver.html?testName=ai-astar) 09:43:48 INFO - 2016-01-22 09:43:48,346 DEBUG : BROWSER_OUTPUT: RSS: Main: 241053696 09:43:48 INFO - 2016-01-22 09:43:48,346 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;104;108;109;109;98;109;111;111 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;120;145;162;122;148;148;123;157;125 09:43:48 INFO - 2016-01-22 09:43:48,347 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;158;159;162;159;160;158;161;158;159 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |3;audio-fft;77;72;72;140;70;72;70;71;150;75 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;92;141;92;93;93;144;93;94;135 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;109;111;110;110;108;108;109;109 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;146;146;146;145;145;145;145 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;96;96;95;103;96 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;59;58;67;59;60;61;57;57;58 09:43:48 INFO - 2016-01-22 09:43:48,348 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;64;64;64;65;64;65;65 09:43:48 INFO - 2016-01-22 09:43:48,349 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;79;80;80;79;79;78;80;79;79 09:43:48 INFO - 2016-01-22 09:43:48,349 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;108;116;109;117;109;118;118;112;112 09:43:48 INFO - 2016-01-22 09:43:48,349 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;152;142;142;147;145;144;147;144;142 09:43:48 INFO - 2016-01-22 09:43:48,349 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;66;67;65;65;63;66;65;74;64 09:43:48 INFO - 2016-01-22 09:43:48,349 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,920 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: __startTimestamp1453484628341__endTimestamp 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:3.89 (3.6%) stddev-sans-first:4.13 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 104.0 108.0 109.0 109.0 98.0 109.0 111.0 111.0 09:43:48 INFO - 2016-01-22 09:43:48,350 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,352 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.60 Median:148.00 stddev:16.39 (11.1%) stddev-sans-first:16.40 09:43:48 INFO - 2016-01-22 09:43:48,352 DEBUG : BROWSER_OUTPUT: Values: 156.0 120.0 145.0 162.0 122.0 148.0 148.0 123.0 157.0 125.0 09:43:48 INFO - 2016-01-22 09:43:48,352 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,352 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.40 Median:159.50 stddev:1.35 (0.8%) stddev-sans-first:1.41 09:43:48 INFO - 2016-01-22 09:43:48,352 DEBUG : BROWSER_OUTPUT: Values: 160.0 158.0 159.0 162.0 159.0 160.0 158.0 161.0 158.0 159.0 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.90 Median:72.00 stddev:30.79 (42.8%) stddev-sans-first:32.45 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: Values: 77.0 72.0 72.0 140.0 70.0 72.0 70.0 71.0 150.0 75.0 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:92.50 stddev:23.03 (24.9%) stddev-sans-first:23.70 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: Values: 91.0 92.0 141.0 92.0 93.0 93.0 144.0 93.0 94.0 135.0 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:1.08 (1.0%) stddev-sans-first:1.00 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 109.0 111.0 110.0 110.0 108.0 108.0 109.0 109.0 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 09:43:48 INFO - 2016-01-22 09:43:48,353 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:95.00 stddev:2.47 (2.6%) stddev-sans-first:2.59 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 103.0 96.0 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.00 stddev:3.12 (5.3%) stddev-sans-first:3.09 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: Values: 56.0 59.0 58.0 67.0 59.0 60.0 61.0 57.0 57.0 58.0 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 64.0 64.0 64.0 65.0 64.0 65.0 65.0 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:0.94 (1.2%) stddev-sans-first:0.67 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: Values: 77.0 79.0 80.0 80.0 79.0 79.0 78.0 80.0 79.0 79.0 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.20 Median:114.50 stddev:3.85 (3.4%) stddev-sans-first:4.09 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: Values: 113.0 108.0 116.0 109.0 117.0 109.0 118.0 118.0 112.0 112.0 09:43:48 INFO - 2016-01-22 09:43:48,354 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.90 Median:144.50 stddev:3.11 (2.2%) stddev-sans-first:3.28 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: Values: 144.0 152.0 142.0 142.0 147.0 145.0 144.0 147.0 144.0 142.0 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.50 stddev:3.07 (4.7%) stddev-sans-first:3.18 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: Values: 64.0 66.0 67.0 65.0 65.0 63.0 66.0 65.0 74.0 64.0 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:43:48 INFO - 2016-01-22 09:43:48,355 DEBUG : BROWSER_OUTPUT: 09:43:49 INFO - 2016-01-22 09:43:49,126 INFO : Browser exited with error code: 0 09:43:49 INFO - 2016-01-22 09:43:49,179 INFO : Completed test kraken (00:00:57) 09:43:49 INFO - 2016-01-22 09:43:49,179 INFO : Starting test v8_7 09:43:49 INFO - 2016-01-22 09:43:49,179 DEBUG : operating with platform_type : linux_ 09:43:49 INFO - 2016-01-22 09:43:49,179 INFO : Initialising browser for v8_7 test... 09:43:49 INFO - 2016-01-22 09:43:49,185 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp44kAxR/profile http://localhost:34243/getInfo.html 09:43:53 INFO - 2016-01-22 09:43:53,047 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:43:53 INFO - 2016-01-22 09:43:53,047 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:43:53 INFO - 2016-01-22 09:43:53,047 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:43:53 INFO - 2016-01-22 09:43:53,047 DEBUG : BROWSER_OUTPUT: __metrics 09:43:53 INFO - 2016-01-22 09:43:53,164 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:43:53 INFO - 2016-01-22 09:43:53,856 INFO : Browser initialized. 09:43:53 INFO - 2016-01-22 09:43:53,856 INFO : Running cycle 1/1 for v8_7 test... 09:43:53 INFO - 2016-01-22 09:43:53,856 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp44kAxR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:44:05 INFO - 2016-01-22 09:44:05,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 137871360 09:44:05 INFO - 2016-01-22 09:44:05,631 DEBUG : BROWSER_OUTPUT: 09:44:26 INFO - 2016-01-22 09:44:26,991 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34243/tests/v8_7/run.html (next: http://localhost:34243/tests/v8_7/run.html) 09:44:27 INFO - 2016-01-22 09:44:27,242 DEBUG : BROWSER_OUTPUT: RSS: Main: 428142592 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: |0;Richards;165.26194017517767 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;173.67141368530739 09:44:27 INFO - 2016-01-22 09:44:27,243 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.09572983533354 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5112.244897959184 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.9377990430622 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |5;Earley;559.9104143337066 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |6;Boyer;10989.010989010989 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |7;RegExp;24166.666666666668 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |8;Splay;529.1005291005291 09:44:27 INFO - 2016-01-22 09:44:27,244 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: __startTimestamp1453484667240__endTimestamp 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.26 Median:165.26 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: Values: 165.3 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,245 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:173.67 Median:173.67 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: Values: 173.7 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: Values: 279.1 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5112.24 Median:5112.24 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: Values: 5112.2 09:44:27 INFO - 2016-01-22 09:44:27,246 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.94 Median:956.94 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: Values: 956.9 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:559.91 Median:559.91 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: Values: 559.9 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,247 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10989.01 Median:10989.01 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: Values: 10989.0 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24166.67 Median:24166.67 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: Values: 24166.7 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:529.10 Median:529.10 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: Values: 529.1 09:44:27 INFO - 2016-01-22 09:44:27,248 DEBUG : BROWSER_OUTPUT: 09:44:27 INFO - 2016-01-22 09:44:27,249 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 09:44:27 INFO - 2016-01-22 09:44:27,249 DEBUG : BROWSER_OUTPUT: Values: 4817.3 09:44:27 INFO - 2016-01-22 09:44:27,249 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:44:27 INFO - 2016-01-22 09:44:27,249 DEBUG : BROWSER_OUTPUT: 09:44:28 INFO - 2016-01-22 09:44:28,010 INFO : Browser exited with error code: 0 09:44:28 INFO - 2016-01-22 09:44:28,025 INFO : Completed test v8_7 (00:00:38) 09:44:28 INFO - 2016-01-22 09:44:28,288 INFO : Completed test suite (00:20:07) 09:44:28 INFO - 2016-01-22 09:44:28,288 DEBUG : Working with test: dromaeo_css 09:44:28 INFO - 2016-01-22 09:44:28,288 DEBUG : Generating results file: dromaeo_css 09:44:28 INFO - 2016-01-22 09:44:28,290 DEBUG : Working with test: kraken 09:44:28 INFO - 2016-01-22 09:44:28,290 DEBUG : Generating results file: kraken 09:44:28 INFO - 2016-01-22 09:44:28,290 INFO : javascript benchmark 09:44:28 INFO - 2016-01-22 09:44:28,290 DEBUG : Working with test: v8_7 09:44:28 INFO - 2016-01-22 09:44:28,290 DEBUG : Generating results file: v8_7 09:44:28 INFO - 2016-01-22 09:44:28,291 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:44:28 INFO - 2016-01-22 09:44:28,345 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 09:44:28 INFO - 2016-01-22 09:44:28,345 DEBUG : process_Request line: dromaeo_css 7151.89 graph.html#tests=[[72,52,35]] 09:44:28 INFO - 2016-01-22 09:44:28,345 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:44:28 INFO - 2016-01-22 09:44:28,376 DEBUG : process_Request line: kraken 1479.10 graph.html#tests=[[232,52,35]] 09:44:28 INFO - 2016-01-22 09:44:28,376 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:44:28 INFO - 2016-01-22 09:44:28,412 DEBUG : process_Request line: v8_7 23026.08 graph.html#tests=[[230,52,35]] 09:44:28 INFO - 2016-01-22 09:44:28,413 INFO : javascript benchmark 09:44:28 INFO - 2016-01-22 09:44:28,417 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3227.112544050386, 3285.4578994465915, 3305.818180000099, 3317.4028674838532, 3331.33373325335, 5341.626086173969, 5355.357357117142, 5356.517913387795, 5362.999999999995, 5366.168243922189, 5356.625036247464, 5380.973095134528, 5382.6232163748555, 5389.434109418514, 5391.137418013119, 4820.686655367407, 4918.016396720653, 4929.186684197112, 4962.6774259673, 4983.7508124593805, 4915.238138088602, 4962.975185124069, 4980.128477516439, 4986.202207646759, 4987.9251811222675, 4899.314096026559, 4906.092372911023, 4960.925586116211, 4965.006998600276, 4965.826196083138, 4838.903221935541, 4944.60443164546, 4953.678010929296, 4961.77672004757, 4966.379202599675, 4958.628102892298, 4978.427480839678, 5011.924821127686, 5012.548870601663, 5036.924446133311, 5186.0, 5236.162214045772, 5249.370075590915, 5250.816221432232, 5291.656042357273, 5812.854678633025, 5899.32157801854, 5901.970490147522, 5919.733611987471, 5951.821445356603, 5884.970575147139, 5895.941040589582, 5897.970510147422, 5900.43945825146, 5902.380250073767, 5370.650907690988, 5405.7837686492885, 5442.700651464168, 5475.780968761244, 5484.890302193974, 5870.119482077637, 5894.970525147432, 5909.881802363929, 5914.497267732291, 5945.554083443672, 5741.368449470555, 5770.105633626795, 5772.191893134965, 5773.162891380726, 5783.074708046693, 5650.29371328584, 5757.913631295534, 5808.157817116495, 5833.970830145822, 5916.26046744157, 5848.356680765112, 5850.5027072699295, 5856.882862342729, 5857.677827719431, 5883.588148829626, 5837.124431335334, 5842.824715258549, 5847.181394604759, 5848.093545500455, 5849.707514624251, 4312.396264522969, 4378.408914796525, 4417.249067658506, 4515.5484451554585, 4518.344839998182, 4050.23045621331, 4054.3107671695884, 4062.654674352654, 4065.410515475299, 4089.1412803311637, 5459.863503412367, 5509.779608815603, 5520.420355862657, 5533.059379905426, 5542.7782888684005, 5593.944060559424, 5704.657720536781, 5758.049921762946, 5843.29880414353, 5843.620164689281, 5514.062609356419, 5518.503334699896, 5530.778768849201, 5536.031194540939, 5540.63985840919, 5121.078205922895, 5133.897322053538, 5135.255387968724, 5141.100307446182, 5171.276021357013, 5659.094544872801, 5815.767369305265, 5973.820785376446, 6025.216721826222, 6034.9396506035255, 5805.042168042226, 5805.3614102447855, 5808.273965754281, 5809.680467574324, 5811.302643682785, 5910.408959104056, 5941.37615550361, 5953.732082056405, 5963.403659634002, 5964.970175149097, 5471.835844924659, 5635.577331700221, 5640.435956404327, 5741.16753070794, 5744.368119506935, 5811.302643682785, 5837.854053648693, 5849.5612829037145, 5850.297964244318, 5853.707314634336, 5712.11462223356, 5724.0, 5724.3703192647945, 5725.828225153252, 5729.140628905698, 5475.178723191553, 5588.636738611977, 5615.438456154352, 5706.771729130955, 5729.3984131665575, 5599.468050535193, 5662.8301150965535, 5671.206031155724, 5673.971630141823, 5675.772969081356, 5525.4474552544425, 5735.483806457439, 5750.511206547323, 5771.278590176228, 5775.075987842093, 5521.1442226454965, 5540.168974653834, 5626.690532020778, 5643.266375371176, 5658.321001479849], "unit": "ms", "name": "dojo.html", "value": 5366.435746579113}, {"lowerIsBetter": false, "replicates": [585.6573904266004, 626.2766504687087, 631.2992578238157, 637.5059329020012, 639.076534407781, 39653.2069358613, 40807.79596102019, 40853.182936341254, 40917.59082409175, 40994.795026024825, 44987.325190122174, 45615.31577026347, 45635.54364456354, 45646.08707825842, 45671.0, 8091.838163236732, 8204.958975205145, 9058.0, 9305.395149315307, 10476.371417714961, 10697.09074728649, 10822.72943176423, 10833.079188269006, 10839.83740243897, 10843.620473283436, 10830.458477076114, 10929.945350273278, 10952.561897524089, 10968.23222374434, 10968.670939871816, 8352.12302708216, 8585.699500517452, 10801.135909127252, 11051.778964420666, 11068.0, 10658.786824263549, 10820.837687434694, 10821.729456763565, 10822.729431764268, 10824.40465774382, 41516.79241603773, 42084.78957605223, 42108.1578368434, 42168.57831421677, 42186.78906605478, 3392.270661807723, 3464.9307013859834, 3639.253952939641, 3657.1771351445977, 3678.264347130558, 31618.683813162035, 32145.6785432145, 32356.514652280235, 32405.189870253198, 35227.82386088053, 4189.7695626740515, 4239.660827133822, 4274.316109422509, 4326.978365108186, 4370.344448332744, 274293.0, 276118.0, 276725.0, 277011.0, 277046.0, 62727.37272627215, 63404.0, 64089.0, 64159.35840641627, 64240.35759642437, 76624.61687691526, 78007.60996195096, 78134.609326953, 78190.6090469544, 78250.60874695703, 17313.134343282785, 17453.516451101546, 17460.214290356966, 17470.47588572345, 17643.029633370286, 13101.213927164401, 13204.537841175512, 13218.867811321956, 13230.272335021666, 13233.073684842162, 21471.14115435396, 21819.67270490944, 21917.561648767256, 21958.89020554919, 22032.44918877009, 8461.40770146084, 8503.14968503157, 8563.828723425497, 8654.480731156025, 8732.345074119467, 1093.6609651008214, 1141.2923987128033, 1147.1568397228032, 1156.150229581257, 1163.9825402618967, 29410.705892940798, 29462.11613651594, 30116.69883301183, 30478.847605762272, 30763.38473230523, 1055.1611468882256, 1079.2121751121567, 1095.4960718069617, 1168.1764356128908, 1233.0505510756668, 12827.486900524062, 13208.471661133448, 13274.070815042855, 13330.0, 15179.772303415457, 438631.0, 438942.0, 442994.0, 443729.0, 443860.0, 53787.462125378246, 53976.190357144675, 54080.72959635177, 54404.183937240974, 54449.1832622511, 1487.1820498725658, 1498.48302335694, 1498.7751837224284, 1501.4444655477325, 1507.3367718203956, 26241.475170496866, 27284.0, 27920.58119128174, 28511.572326414705, 29612.0, 52738.0, 53299.46700532945, 53342.99999999845, 53397.73301133625, 53409.46590534045, 311069.0, 311574.0, 312723.0, 312788.0, 313430.0, 28299.717002829708, 33497.49753753647, 33559.83220083982, 33635.66364336325, 33770.66229337675, 37249.0, 37727.81136094302, 38102.047448814, 38211.80894095512, 38668.03329916662, 33079.66920330862, 33212.50181247235, 33278.5008224872, 33354.83322583372, 33511.16222094467, 29130.27174320659, 30646.0, 33638.663613363555, 34270.828645856614, 36328.45507317445, 33040.66959330472, 33189.668103318654, 33562.0, 33716.662833372335, 33774.0, 28076.85961570179, 30247.395052099277, 31603.525947111273, 32559.511607325436, 32652.0, 22350.21774237894, 22441.10235590558, 22543.323700289016, 22608.886955565118, 22779.772202277763], "unit": "ms", "name": "ext.html", "value": 19772.19790764906}, {"lowerIsBetter": false, "replicates": [389.2545774841176, 399.2075729676593, 400.5773908526505, 401.44400005991713, 401.8854626431467, 14387.928060359684, 17699.203535840887, 18069.638607227847, 18089.1859866306, 19855.5036124097, 17322.56693582661, 17383.565410864736, 18181.090945452706, 18210.45368638943, 18243.270269189215, 6180.412860778219, 6279.120923070751, 6291.3708629136945, 6299.275583307909, 6321.77873774418, 6117.69411529424, 6222.751089956397, 6229.6573688447115, 6248.968755156217, 6277.0584412338285, 4465.218586747323, 4585.380973568575, 6217.191765070557, 6221.844453888666, 6222.0, 6661.70022348993, 6839.179298484214, 6889.138857642794, 6899.206591241996, 6903.344182302673, 5132.307138536287, 5281.181416880389, 5285.286486324374, 5285.577153827685, 6607.702653380609, 13357.553859896912, 13579.049466537346, 21687.891560542095, 22371.664425033636, 22404.66393004106, 2220.8667479951255, 2265.456290490287, 2329.685492458513, 2392.09100541793, 2472.938176545583, 10177.0, 10369.79260414795, 10581.682549523526, 12582.68543286416, 13465.259410732406, 1919.6256729937668, 1972.4575741671013, 1983.1968052938582, 2013.1142297389101, 2017.3040301096098, 67012.32987670158, 67526.0, 68090.0, 68150.65924670345, 68248.3175168247, 10978.560857565768, 11035.172362072877, 11155.05182059534, 11212.55149793999, 11218.719532011602, 14907.329170187368, 14919.881008924374, 14925.029873058435, 14925.477608283874, 14938.32777525014, 8261.463004904783, 8308.12764659714, 8442.62008209632, 8494.193051660082, 8500.0, 8072.0, 8091.959540202261, 8169.30560902318, 8169.877451838227, 8201.056878458934, 4752.049590081998, 4773.665843390999, 4787.8563643090765, 4850.6362022848425, 4894.706317620954, 1786.916938402713, 1836.540864783804, 1889.3576184097474, 1918.8656794024284, 1939.93210237641, 913.7167478081683, 978.3445091788519, 1040.6877936618985, 1042.9883013476915, 1087.842262871895, 10451.529681164366, 17320.566985825455, 17540.0, 17748.55628609295, 18066.90966545159, 495.68028621538906, 497.8133200049981, 499.6402590135097, 517.4541651590757, 529.5366554265019, 6362.713677884506, 6502.0, 6635.701393437307, 6699.263081061079, 6710.966445167743, 589224.0, 590254.0, 598870.0, 604518.0, 607225.0, 85325.0, 85823.57088214644, 86471.56764216139, 87226.0, 88069.0, 543.4293991309189, 544.2978557660653, 545.7598656591088, 545.7707762739659, 546.5136028934252, 11134.164937629872, 11295.096392288433, 11354.602588909349, 11354.772904541698, 11378.829317560077, 37273.068173295884, 37920.81039594785, 38946.803541793364, 39001.41497877591, 40128.197436050534, 46967.76516117398, 47400.0, 47917.0, 49370.753146235475, 49769.25346119739, 9728.416295022322, 9796.118349348593, 10063.094321511098, 10077.496125193858, 10139.7465063374, 11883.702907427383, 11919.106067044833, 11935.52257909674, 12022.158448908493, 14906.850931490546, 14666.26668666541, 15151.621209469851, 15204.923975380052, 15207.467738629095, 15375.61560960985, 14727.263636817903, 14914.477993270184, 14924.403023879358, 15449.459268925533, 15662.216889155725, 10131.189504839447, 10261.2304077196, 10360.740981475223, 11071.944640276746, 11128.221024528206, 16241.269142888363, 16481.17594120265, 16695.916520417806, 16755.1622418881, 16818.0, 17050.232739526502, 17051.829481705023, 17383.130843458028, 17647.911760441115, 17913.820861791217], "unit": "ms", "name": "jquery.html", "value": 8973.402993596601}, {"lowerIsBetter": false, "replicates": [1354.4514471638977, 1547.4042493639952, 1550.3178601415377, 1809.7285407188922, 1846.9907650461741, 6882.6558672066385, 8029.879551806727, 8213.0, 8222.629981650825, 8230.218129277724, 7767.495112817663, 7927.841443171147, 7935.127136015034, 7942.007249093864, 7944.245296696819, 1986.920523179071, 2026.3819535041807, 2026.898655067248, 2249.460129568908, 2281.121768119278, 2261.649444336122, 2322.1175953137786, 2322.3729593009875, 2325.5697695926306, 2326.1044497868356, 2270.6594010898334, 2305.3199306204624, 2326.127702111708, 2329.126577533425, 2330.5455436189864, 2281.2015794471968, 2291.7822806833324, 2316.085146367184, 2320.6751054852334, 2344.6834677318516, 2289.713785776778, 2312.0405031911737, 2317.7566355532763, 2319.4549280918973, 2328.871912044837, 8226.136255693125, 8288.046874609376, 8317.459365141247, 8323.167683231628, 8330.541820199887, 4702.5297470253045, 4781.402324709411, 4789.137955168068, 4792.520747925215, 4796.808127674889, 2981.2844917219927, 3030.1818509002665, 3063.4638938185726, 3069.109958112145, 3095.5511450839726, 989.6734077754396, 995.875515560555, 1023.9078482936571, 1044.650042235852, 1048.5176818663424, 10971.286866353661, 11100.167487438393, 11115.888841111648, 11117.388543630097, 11149.0, 4192.979035104805, 4220.177065472203, 4253.149370125925, 4299.032717638569, 4341.544137865541, 9336.11306925841, 9356.06439356059, 9356.766080848034, 9394.624215031323, 9410.576524056434, 4844.394450693663, 4863.3434486344595, 4868.513148685103, 4895.632827537949, 4904.362432883702, 4472.306792447176, 4530.773461326987, 4533.433320834895, 4534.77326133692, 4541.6593755468475, 3232.82219477931, 3257.4625186843905, 3355.681210285019, 3386.8306584670668, 3528.400171970771, 1103.61925135828, 1122.2649164797072, 1126.1385040444068, 1131.2816361610437, 1135.5514571744116, 1060.1095080132725, 1062.9574817007388, 1136.4545018391218, 1277.8722127787332, 1402.8737413632618, 7671.232876712395, 7683.731069412655, 7712.344450721752, 7758.612069396508, 7920.881186782203, 635.6313338263795, 650.5153660522941, 674.5345711459175, 682.4028974647184, 790.774629230678, 6139.324674285825, 6232.532560058014, 6234.470070044006, 6240.126382306481, 6245.594036387621, 9385.765355866159, 9400.764980875532, 9525.428474291564, 9530.666426675032, 9648.228141748643, 9414.19979301767, 9470.479123648265, 9564.187044101329, 9574.186194173433, 9580.95209523948, 7383.778486645409, 7567.470277080662, 7615.504992175491, 7654.119776225694, 7658.69365225407, 7043.225245222919, 7049.859002820018, 7067.929320706727, 7081.00865878767, 7092.858142837217, 8407.327413806759, 8442.366822488215, 8497.192766687154, 8612.18184272493, 8634.5251011195, 8558.358123140664, 8625.525596092275, 8754.73735787902, 8766.0, 8771.692990745294, 6895.103636527219, 7114.395276401357, 7126.465515086285, 7139.286071392819, 7143.1428228612895, 6391.488680905423, 6480.449161821298, 6480.870382592416, 7613.314801667876, 7688.538687678757, 7371.8157046074275, 7519.172890982097, 7519.285667861544, 7531.096268447822, 7546.773596792105, 7317.3780228679025, 7334.266573342837, 7401.888971665537, 7456.142543607554, 7500.287472690085, 7275.272472752894, 7288.453365997465, 7320.560766354036, 7377.889331660137, 7426.665800038903, 6374.0, 6398.776042838665, 6401.967990160019, 6410.839729006812, 6414.839629009312, 6419.35806419373, 6871.381575658214, 6898.931010689829, 6899.517033807585, 6903.6203008835], "unit": "ms", "name": "mootools.html", "value": 4505.192791010188}, {"lowerIsBetter": false, "replicates": [362.77145398399006, 365.57410616631626, 370.172664095746, 374.10028880542296, 376.3846111607523, 5875.353711091781, 5883.470487656115, 5901.645901245917, 5902.49828764555, 5970.611910225833, 5826.563007774424, 5839.5620328475325, 5840.299164100304, 5843.824685259438, 5846.88306233875, 4629.0, 4635.490096089427, 4636.09596128755, 4638.721676699409, 4643.8142474300985, 4527.615152712024, 4646.0, 4669.649776266777, 4669.789859456333, 4691.648126390517, 4603.5166307537565, 4609.377734005917, 4611.054733779567, 4613.146567884935, 4623.491415944243, 4401.185780630562, 4502.5047244803045, 4608.562186592285, 4610.239310513761, 4612.746298953573, 3988.840446382141, 4041.211963667086, 4052.6960477963976, 4052.817623206933, 4057.6145266199665, 5688.374278829326, 5689.231953686241, 5695.515881150066, 5708.71456427177, 5717.0, 5133.84598462043, 5242.895142097175, 5267.26258323835, 5367.785288588452, 5374.91937620936, 6306.054091886208, 6315.463185629227, 6316.463100636452, 6320.273168585625, 6322.683865806691, 4514.164879497303, 4521.954780452219, 4524.61540769035, 4527.071950250223, 4529.162105010583, 6375.139356186902, 6472.3204063573585, 6548.377904099103, 6553.475721942234, 6555.541112122153, 4593.678442508993, 4616.976915115403, 4618.399608050931, 4620.653450991189, 4624.606908412756, 4669.41632295963, 4675.742834144155, 4678.485366609671, 4679.1577516046755, 4680.695754775997, 5331.040412725747, 5348.545373643284, 5356.91964620531, 5357.116075847442, 5358.142697168434, 6251.78118765841, 6326.9683651581445, 6353.745850166034, 6355.523335749837, 6364.236291645032, 1626.4470080172798, 1631.6247263129546, 1642.89321194122, 1644.4819881737214, 1645.2596331650566, 1116.8883111688929, 1120.7534342444649, 1125.3697929159696, 1130.8360287758394, 1136.0003197186588, 6142.539309551801, 6147.815565533041, 6149.877002459926, 6152.907706384408, 6156.322804491502, 6471.611703297723, 6560.0, 6566.638834864033, 6566.835829104215, 6571.901421478682, 6014.127951447016, 6128.846778830565, 6137.877242455215, 6140.723667434976, 6144.539159563051, 3521.330947120039, 3544.592371877267, 3544.6278140794843, 3550.982245088758, 3551.5027896094566, 6717.059611654372, 6729.25978142404, 6729.966350168218, 6745.932540674628, 6752.5948443093575, 6459.224893012869, 6603.405693487609, 6666.100076489612, 6690.59856408617, 6693.497987650849, 6613.966930165415, 6768.2216545097945, 6782.898256526156, 6787.5588086774205, 6799.898001529981, 6592.604443733391, 6605.5376123670885, 6611.504137189634, 6614.040964060088, 6616.206055273398, 6371.012493063583, 6499.440134367623, 6533.216014078341, 6535.542512024303, 6537.953927371597, 6773.729050838108, 6784.728610855511, 6785.9660701696175, 6786.796396108125, 6790.117284752951, 4583.152116858392, 4585.977070114628, 4587.541245875385, 4589.58693717567, 4591.609713174284, 6467.126937863516, 6608.074869518367, 6611.272759996493, 6615.4046135848, 6625.602463852184, 6447.774327898501, 6458.22501299847, 6463.967680161569, 6468.0, 6479.060536222316, 6431.678416079271, 6450.290468048417, 6453.9354606453335, 6456.386643268882, 6462.4183823456115, 6241.126242325987, 6312.842178945563, 6346.0, 6348.079528468438, 6360.77737279193, 6454.838629034311, 6461.483081353386, 6463.967680161569, 6466.51501137426, 6469.935300647122, 6258.217722784652, 6275.152854364603, 6277.12120303167, 6283.685815709105, 6283.96858015707], "unit": "ms", "name": "prototype.html", "value": 4834.63927940564}, {"lowerIsBetter": false, "replicates": [803.6022169026332, 806.3191530662525, 835.3567752830321, 911.8221946720383, 928.0302084321883, 12747.490100395984, 12926.224426534414, 12940.0, 12972.221666700005, 12973.091883568153, 11897.583584574542, 11986.101042421831, 12014.0, 12016.0, 12044.69888252794, 738.8079099434159, 747.1184002876604, 751.4439314906984, 897.9146981036791, 900.0234745301356, 907.0204179486173, 909.9954500227523, 914.236612428623, 915.1077699243251, 916.1617120334886, 957.0477375011873, 962.06679520865, 962.4610218277752, 963.2294164668273, 963.36899330938, 771.4368510986983, 782.5656760497922, 932.365991126034, 953.7997020625677, 958.2621381536213, 707.734599525178, 709.0286307758353, 709.1667290933133, 709.6345382128208, 710.0201721624126, 12287.201331913397, 12604.873951260462, 12606.306653134074, 12607.243565386014, 12608.495660173581, 11694.707632309175, 12130.696732581757, 12708.237505749685, 13006.479740810357, 13178.406971686298, 10850.782984340349, 11160.16298777595, 11198.776024479464, 11270.774584508346, 11462.48418821155, 3819.1216020315483, 3821.8662346817728, 3858.305505009097, 3874.3219936511277, 3876.7092468064725, 46245.30632040522, 46387.536124638995, 46964.7651761739, 47329.76335118302, 49407.752961235325, 9456.763580910532, 9571.569279382444, 10344.586216551405, 10450.68647940563, 10484.528196231187, 16219.837801622069, 19226.0, 19378.515537111685, 19438.22247110128, 19466.90266548658, 7934.127246002935, 7950.0, 8006.359491240687, 8124.309433698084, 8158.388120890957, 4420.248557745191, 4438.1567502174485, 4469.217886869785, 4500.0, 4523.864284071417, 3256.9348613027605, 3280.1471617379652, 3283.7044665980175, 3286.1291757684235, 3288.93422131556, 584.2288179602969, 584.4068270705153, 598.7545106506311, 599.5054080383701, 604.921360223177, 562.5583916625429, 661.5071771530145, 670.8825955457776, 695.8817001109824, 740.8036870229394, 8607.440516366416, 8768.912310876936, 8774.429662071945, 8919.821603567892, 9003.36976411658, 509.8241106818142, 551.6286886461355, 570.5521165884843, 638.5944924972583, 712.803978905805, 3946.2896678597554, 4030.7380020298588, 4033.798310084484, 4055.3916912463364, 4101.815418306183, 57782.13326800102, 58201.12698309529, 58524.707376462844, 58547.0, 58560.0, 51523.742381288605, 51595.484045159064, 51794.48205517972, 52122.73938630358, 52271.0, 46336.304955425694, 47003.76498117556, 47407.28889066667, 48536.27195592069, 48640.27039594409, 7630.1606823248285, 7669.884951725617, 7943.44395892282, 8020.879686804586, 8054.31538319226, 35114.29771404507, 35366.82316588401, 35798.82100589584, 35882.64117358898, 36245.0, 43197.78401107974, 43230.783846080565, 45927.311090333, 47170.76414617905, 47190.76404617955, 9869.210463163074, 10163.94918025405, 10205.897941020496, 10836.729081773019, 10872.836907446554, 12869.935650321688, 12929.612111636667, 13152.079354445097, 13278.734425311633, 13901.791473128114, 11426.744656513161, 11504.654860354203, 11637.476313565741, 11959.521619135137, 12069.456874440846, 11599.76800464003, 11681.217285937199, 11710.355930423908, 11780.587679431179, 12559.686007849878, 9353.953230233805, 11067.501962411872, 11684.18210725241, 11865.347405892759, 12136.878631213927, 9978.501074946369, 10061.849072264069, 10154.441505717257, 10331.896681033093, 10461.110805581611, 9789.559469823733, 9821.95089024579, 9920.503974801375, 10077.647282345082, 10196.949015254877], "unit": "ms", "name": "yui.html", "value": 6452.222158027345}], "name": "dromaeo_css", "value": 7151.890928732707}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 104.0, 108.0, 109.0, 109.0, 98.0, 109.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [156.0, 120.0, 145.0, 162.0, 122.0, 148.0, 148.0, 123.0, 157.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.6}, {"lowerIsBetter": true, "replicates": [160.0, 158.0, 159.0, 162.0, 159.0, 160.0, 158.0, 161.0, 158.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.4}, {"lowerIsBetter": true, "replicates": [77.0, 72.0, 72.0, 140.0, 70.0, 72.0, 70.0, 71.0, 150.0, 75.0], "unit": "ms", "name": "audio-fft", "value": 86.9}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 141.0, 92.0, 93.0, 93.0, 144.0, 93.0, 94.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 109.0, 111.0, 110.0, 110.0, 108.0, 108.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 103.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [56.0, 59.0, 58.0, 67.0, 59.0, 60.0, 61.0, 57.0, 57.0, 58.0], "unit": "ms", "name": "json-parse-financial", "value": 59.2}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.4}, {"lowerIsBetter": true, "replicates": [77.0, 79.0, 80.0, 80.0, 79.0, 79.0, 78.0, 80.0, 79.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.0}, {"lowerIsBetter": true, "replicates": [113.0, 108.0, 116.0, 109.0, 117.0, 109.0, 118.0, 118.0, 112.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.2}, {"lowerIsBetter": true, "replicates": [144.0, 152.0, 142.0, 142.0, 147.0, 145.0, 144.0, 147.0, 144.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.9}, {"lowerIsBetter": true, "replicates": [64.0, 66.0, 67.0, 65.0, 65.0, 63.0, 66.0, 65.0, 74.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.9}], "name": "kraken", "value": 1479.1000000000004}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.26194017517767], "unit": "score", "name": "Richards", "value": 213.61240199999997}, {"lowerIsBetter": false, "replicates": [173.67141368530739], "unit": "score", "name": "DeltaBlue", "value": 380.70744399999995}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5112.244897959184], "unit": "score", "name": "Decrypt", "value": 52.067341317365305}, {"lowerIsBetter": false, "replicates": [956.9377990430622], "unit": "score", "name": "RayTrace", "value": 773.2885049999996}, {"lowerIsBetter": false, "replicates": [559.9104143337066], "unit": "score", "name": "Earley", "value": 1190.3029180000005}, {"lowerIsBetter": false, "replicates": [10989.010989010989], "unit": "score", "name": "Boyer", "value": 60.64813299999995}, {"lowerIsBetter": false, "replicates": [24166.666666666668], "unit": "score", "name": "RegExp", "value": 37.69593103448274}, {"lowerIsBetter": false, "replicates": [529.1005291005291], "unit": "score", "name": "Splay", "value": 154.01798999999997}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23026.084771490525}]} 09:44:28 INFO - RETURN: dromaeo_css: 7151.89 09:44:28 INFO - RETURN: kraken: 1479.10 09:44:28 INFO - RETURN: v8_7: 23026.08 09:44:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "23026.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1479.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7151.89"}}} 09:44:28 INFO - Return code: 0 09:44:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:44:28 INFO - # TBPL SUCCESS # 09:44:28 INFO - Running post-action listener: _resource_record_post_action 09:44:28 INFO - Running post-run listener: _resource_record_post_run 09:44:29 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 278528; Write bytes: 271204352; Read time: 68; Write time: 189768 09:44:29 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 4657152; Read time: 0; Write time: 232 09:44:29 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 278528; Write bytes: 266547200; Read time: 68; Write time: 189536 09:44:29 INFO - Running post-run listener: _upload_blobber_files 09:44:29 INFO - Blob upload gear active. 09:44:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:44:29 INFO - Copying logs to upload dir... 09:44:29 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1261.205343 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-01-22 09:44:29.609072) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:44:29.612429) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010134 build_url: 'https://queue.taskcluster.net/v1/task/ZDxVibeIQAelFrXkPXgRVQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:44:29.669624) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:44:29.669978) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1453483143.264309-1599686108 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004585 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:44:29.721014) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 09:44:29.721538) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 09:44:29.722062) ========= ========= Total master_lag: 0.26 =========