builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1493993303.41 results: success (0) revision: 8872ad4d52b6b517dc9d2a9f62c5c75ceda18790 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.408296) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.408745) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.409015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.423876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.424263) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-05 07:08:24-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.5M=0s 2017-05-05 07:08:24 (49.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121410 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.560143) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.560535) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019724 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.610389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:23.610729) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8872ad4d52b6b517dc9d2a9f62c5c75ceda18790 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 8872ad4d52b6b517dc9d2a9f62c5c75ceda18790 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-05 07:08:24,308 truncating revision to first 12 chars 2017-05-05 07:08:24,308 Setting DEBUG logging. 2017-05-05 07:08:24,308 attempt 1/10 2017-05-05 07:08:24,308 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8872ad4d52b6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 07:08:24,874 unpacking tar archive at: mozilla-central-8872ad4d52b6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.931621 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:24.556007) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:24.556353) ========= script_repo_revision: 8872ad4d52b6b517dc9d2a9f62c5c75ceda18790 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:24.556758) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:24.557023) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 07:08:24.566985) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-05 07:08:24.567400) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:08:25 INFO - MultiFileLogger online at 20170505 07:08:25 in /builds/slave/test 07:08:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:08:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:08:25 INFO - {'append_to_log': False, 07:08:25 INFO - 'base_work_dir': '/builds/slave/test', 07:08:25 INFO - 'blob_upload_branch': 'Firefox', 07:08:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:08:25 INFO - 'branch': 'Firefox', 07:08:25 INFO - 'buildbot_json_path': 'buildprops.json', 07:08:25 INFO - 'config_files': ('talos/linux_config.py',), 07:08:25 INFO - 'default_actions': ('clobber', 07:08:25 INFO - 'read-buildbot-config', 07:08:25 INFO - 'download-and-extract', 07:08:25 INFO - 'populate-webroot', 07:08:25 INFO - 'create-virtualenv', 07:08:25 INFO - 'install', 07:08:25 INFO - 'run-tests'), 07:08:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:08:25 INFO - 'download_minidump_stackwalk': True, 07:08:25 INFO - 'download_symbols': 'ondemand', 07:08:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:08:25 INFO - 'tooltool.py': '/tools/tooltool.py', 07:08:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:08:25 INFO - '/tools/misc-python/virtualenv.py')}, 07:08:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:08:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:08:25 INFO - 'gecko_profile': False, 07:08:25 INFO - 'gecko_profile_interval': 0, 07:08:25 INFO - 'installer_path': 'installer.exe', 07:08:25 INFO - 'log_level': 'info', 07:08:25 INFO - 'log_name': 'talos', 07:08:25 INFO - 'log_to_console': True, 07:08:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:08:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:08:25 INFO - 'opt_config_files': (), 07:08:25 INFO - 'pip_index': False, 07:08:25 INFO - 'suite': 'dromaeojs', 07:08:25 INFO - 'system_bits': '32', 07:08:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:08:25 INFO - 'title': 'talos-linux64-ix-035', 07:08:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:08:25 INFO - 'use_talos_json': True, 07:08:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:08:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:08:25 INFO - 'work_dir': 'build'} 07:08:25 INFO - [mozharness: 2017-05-05 14:08:25.368893Z] Running clobber step. 07:08:25 INFO - Running pre-action listener: _resource_record_pre_action 07:08:25 INFO - Running main action method: clobber 07:08:25 INFO - rmtree: /builds/slave/test/build 07:08:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:08:26 INFO - Running post-action listener: _resource_record_post_action 07:08:26 INFO - [mozharness: 2017-05-05 14:08:26.308782Z] Finished clobber step (success) 07:08:26 INFO - [mozharness: 2017-05-05 14:08:26.308882Z] Running read-buildbot-config step. 07:08:26 INFO - Running pre-action listener: _resource_record_pre_action 07:08:26 INFO - Running main action method: read_buildbot_config 07:08:26 INFO - Using buildbot properties: 07:08:26 INFO - { 07:08:26 INFO - "project": "", 07:08:26 INFO - "product": "firefox", 07:08:26 INFO - "who": "cbook@mozilla.com", 07:08:26 INFO - "installer_path": "public/build/target.tar.bz2", 07:08:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:08:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:08:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:08:26 INFO - "stage_platform": "linux64-pgo", 07:08:26 INFO - "basedir": "/builds/slave/test", 07:08:26 INFO - "buildnumber": 168, 07:08:26 INFO - "platform": "ubuntu64_hw", 07:08:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:08:26 INFO - "slavebuilddir": "test", 07:08:26 INFO - "taskId": "UVyJPSTQRWmGRNJoRY1V3A", 07:08:26 INFO - "branch": "mozilla-central", 07:08:26 INFO - "script_repo_revision": "production", 07:08:26 INFO - "revision": "8872ad4d52b6b517dc9d2a9f62c5c75ceda18790", 07:08:26 INFO - "slavename": "talos-linux64-ix-035", 07:08:26 INFO - "repo_path": "mozilla-central" 07:08:26 INFO - } 07:08:26 INFO - Finding installer, test and symbols from parent task. 07:08:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVyJPSTQRWmGRNJoRY1V3A'}, attempt #1 07:08:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVyJPSTQRWmGRNJoRY1V3A'}, attempt #1 07:08:27 INFO - Task dependencies: eBanjAKoTzSO6ZvRvgOtZg 07:08:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg'}, attempt #1 07:08:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2 07:08:27 INFO - Set test_url: https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/test_packages.json 07:08:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.crashreporter-symbols.zip 07:08:27 INFO - Running post-action listener: _resource_record_post_action 07:08:27 INFO - [mozharness: 2017-05-05 14:08:27.721157Z] Finished read-buildbot-config step (success) 07:08:27 INFO - [mozharness: 2017-05-05 14:08:27.721249Z] Running download-and-extract step. 07:08:27 INFO - Running pre-action listener: _resource_record_pre_action 07:08:27 INFO - Running main action method: download_and_extract 07:08:27 INFO - mkdir: /builds/slave/test/build/tests 07:08:27 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:27 INFO - trying https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.test_packages.json 07:08:27 INFO - Downloading https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:08:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:08:28 INFO - Downloaded 986 bytes. 07:08:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:08:28 INFO - Using the following test package requirements: 07:08:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:08:28 INFO - u'common': [u'target.common.tests.zip'], 07:08:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:08:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:08:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:08:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:08:28 INFO - u'mozbase': [u'target.common.tests.zip'], 07:08:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:08:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:08:28 INFO - u'web-platform': [u'target.common.tests.zip', 07:08:28 INFO - u'target.web-platform.tests.zip'], 07:08:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:08:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:08:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.common.tests.zip 07:08:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:08:28 INFO - Fetch https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.common.tests.zip into memory 07:08:29 INFO - Content-Length response header: 60560012 07:08:29 INFO - Bytes received: 60560012 07:08:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:08:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.common.tests.zip 07:08:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:08:39 INFO - Fetch https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.common.tests.zip into memory 07:08:40 INFO - Content-Length response header: 60560012 07:08:40 INFO - Bytes received: 60560012 07:08:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.talos.tests.zip 07:08:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:08:51 INFO - Fetch https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.talos.tests.zip into memory 07:08:51 INFO - Content-Length response header: 14408809 07:08:51 INFO - Bytes received: 14408809 07:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:52 INFO - trying https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2 07:08:52 INFO - Downloading https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:08:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:08:53 INFO - Downloaded 64023225 bytes. 07:08:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2 07:08:53 INFO - mkdir: /builds/slave/test/properties 07:08:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:08:53 INFO - Writing to file /builds/slave/test/properties/build_url 07:08:53 INFO - Contents: 07:08:53 INFO - build_url:https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2 07:08:53 INFO - Running post-action listener: _resource_record_post_action 07:08:53 INFO - Running post-action listener: set_extra_try_arguments 07:08:53 INFO - [mozharness: 2017-05-05 14:08:53.236378Z] Finished download-and-extract step (success) 07:08:53 INFO - [mozharness: 2017-05-05 14:08:53.236473Z] Running populate-webroot step. 07:08:53 INFO - Running pre-action listener: _resource_record_pre_action 07:08:53 INFO - Running main action method: populate_webroot 07:08:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:08:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:08:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:08:53 INFO - '--tppagecycles', 07:08:53 INFO - '7'], 07:08:53 INFO - 'tests': ['tsvgm']}}, 07:08:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:08:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:08:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:08:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:08:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:08:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:08:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['damp', 'tps']}, 07:08:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'tests': ['damp', 'tps']}, 07:08:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['dromaeo_dom']}, 07:08:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:08:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:08:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:08:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['a11yr', 07:08:53 INFO - 'ts_paint', 07:08:53 INFO - 'tpaint', 07:08:53 INFO - 'sessionrestore', 07:08:53 INFO - 'sessionrestore_no_auto_restore', 07:08:53 INFO - 'tabpaint']}, 07:08:53 INFO - 'other-e10s': {'tests': ['a11yr', 07:08:53 INFO - 'ts_paint', 07:08:53 INFO - 'tpaint', 07:08:53 INFO - 'sessionrestore', 07:08:53 INFO - 'sessionrestore_no_auto_restore', 07:08:53 INFO - 'tabpaint']}, 07:08:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:08:53 INFO - 'ts_paint', 07:08:53 INFO - 'tpaint', 07:08:53 INFO - 'sessionrestore', 07:08:53 INFO - 'sessionrestore_no_auto_restore', 07:08:53 INFO - 'tabpaint']}, 07:08:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:08:53 INFO - 'ts_paint', 07:08:53 INFO - 'tpaint', 07:08:53 INFO - 'sessionrestore', 07:08:53 INFO - 'sessionrestore_no_auto_restore', 07:08:53 INFO - 'tabpaint']}, 07:08:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['a11yr', 07:08:53 INFO - 'ts_paint', 07:08:53 INFO - 'tpaint', 07:08:53 INFO - 'sessionrestore', 07:08:53 INFO - 'sessionrestore_no_auto_restore', 07:08:53 INFO - 'tabpaint']}, 07:08:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['a11yr', 07:08:53 INFO - 'ts_paint', 07:08:53 INFO - 'tpaint', 07:08:53 INFO - 'sessionrestore', 07:08:53 INFO - 'sessionrestore_no_auto_restore', 07:08:53 INFO - 'tabpaint']}, 07:08:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:08:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:08:53 INFO - 'bloom_basic_ref']}, 07:08:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['tsvgx', 07:08:53 INFO - 'tsvgr_opacity', 07:08:53 INFO - 'tart', 07:08:53 INFO - 'tscrollx', 07:08:53 INFO - 'cart', 07:08:53 INFO - 'tsvg_static']}, 07:08:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:08:53 INFO - 'tsvgr_opacity', 07:08:53 INFO - 'tart', 07:08:53 INFO - 'tscrollx', 07:08:53 INFO - 'cart', 07:08:53 INFO - 'tsvg_static']}, 07:08:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'talos_options': ['--disable-e10s'], 07:08:53 INFO - 'tests': ['tp5o']}, 07:08:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:08:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'talos_options': ['--disable-e10s', 07:08:53 INFO - '--xperf_path', 07:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:08:53 INFO - 'tests': ['tp5n']}, 07:08:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:08:53 INFO - 'talos_options': ['--xperf_path', 07:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:08:53 INFO - 'tests': ['tp5n']}}, 07:08:53 INFO - 'talos.zip': {'path': '', 07:08:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:08:53 INFO - Running post-action listener: _resource_record_post_action 07:08:53 INFO - [mozharness: 2017-05-05 14:08:53.250069Z] Finished populate-webroot step (success) 07:08:53 INFO - [mozharness: 2017-05-05 14:08:53.250165Z] Running create-virtualenv step. 07:08:53 INFO - Running pre-action listener: _resource_record_pre_action 07:08:53 INFO - Running main action method: create_virtualenv 07:08:53 INFO - Creating virtualenv /builds/slave/test/build/venv 07:08:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:08:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:08:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:08:53 INFO - Using real prefix '/usr' 07:08:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:08:53 INFO - Installing distribute.............................................................................................................................................................................................done. 07:08:55 INFO - Installing pip.................done. 07:08:55 INFO - Return code: 0 07:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:08:56 INFO - Reading from file tmpfile_stdout 07:08:56 INFO - Output received: 07:08:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:08:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:08:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:08:56 INFO - Running command: ['/builds/slave/test/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/build 07:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:08:56 INFO - Using env: {'DISPLAY': ':0', 07:08:56 INFO - 'HOME': '/home/cltbld', 07:08:56 INFO - 'LANG': 'en_US.UTF-8', 07:08:56 INFO - 'LANGUAGE': 'en_US:en', 07:08:56 INFO - 'LOGNAME': 'cltbld', 07:08:56 INFO - 'MAIL': '/var/mail/cltbld', 07:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:56 INFO - 'MOZ_NO_REMOTE': '1', 07:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:56 INFO - 'NO_EM_RESTART': '1', 07:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:56 INFO - 'PWD': '/builds/slave/test', 07:08:56 INFO - 'SHELL': '/bin/bash', 07:08:56 INFO - 'SHLVL': '1', 07:08:56 INFO - 'TERM': 'linux', 07:08:56 INFO - 'TMOUT': '86400', 07:08:56 INFO - 'USER': 'cltbld', 07:08:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:56 INFO - '_': '/tools/buildbot/bin/python'} 07:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:08:56 INFO - Cleaning up... 07:08:56 INFO - Return code: 0 07:08:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:08:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:08:56 INFO - Running command: ['/builds/slave/test/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>=3.1.1'] in /builds/slave/test/build 07:08:56 INFO - Copy/paste: /builds/slave/test/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>=3.1.1 07:08:56 INFO - Using env: {'DISPLAY': ':0', 07:08:56 INFO - 'HOME': '/home/cltbld', 07:08:56 INFO - 'LANG': 'en_US.UTF-8', 07:08:56 INFO - 'LANGUAGE': 'en_US:en', 07:08:56 INFO - 'LOGNAME': 'cltbld', 07:08:56 INFO - 'MAIL': '/var/mail/cltbld', 07:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:56 INFO - 'MOZ_NO_REMOTE': '1', 07:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:56 INFO - 'NO_EM_RESTART': '1', 07:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:56 INFO - 'PWD': '/builds/slave/test', 07:08:56 INFO - 'SHELL': '/bin/bash', 07:08:56 INFO - 'SHLVL': '1', 07:08:56 INFO - 'TERM': 'linux', 07:08:56 INFO - 'TMOUT': '86400', 07:08:56 INFO - 'USER': 'cltbld', 07:08:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:56 INFO - '_': '/tools/buildbot/bin/python'} 07:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:56 INFO - Downloading/unpacking psutil>=3.1.1 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:08:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:08:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:08:57 INFO - Installing collected packages: psutil 07:08:57 INFO - Running setup.py install for psutil 07:08:57 INFO - building 'psutil._psutil_linux' extension 07:08:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:08:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:08:57 INFO - building 'psutil._psutil_posix' extension 07:08:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:08:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:08:58 INFO - Successfully installed psutil 07:08:58 INFO - Cleaning up... 07:08:58 INFO - Return code: 0 07:08:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:08:58 INFO - Running command: ['/builds/slave/test/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.3'] in /builds/slave/test/build 07:08:58 INFO - Copy/paste: /builds/slave/test/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.3 07:08:58 INFO - Using env: {'DISPLAY': ':0', 07:08:58 INFO - 'HOME': '/home/cltbld', 07:08:58 INFO - 'LANG': 'en_US.UTF-8', 07:08:58 INFO - 'LANGUAGE': 'en_US:en', 07:08:58 INFO - 'LOGNAME': 'cltbld', 07:08:58 INFO - 'MAIL': '/var/mail/cltbld', 07:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:58 INFO - 'MOZ_NO_REMOTE': '1', 07:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:58 INFO - 'NO_EM_RESTART': '1', 07:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:58 INFO - 'PWD': '/builds/slave/test', 07:08:58 INFO - 'SHELL': '/bin/bash', 07:08:58 INFO - 'SHLVL': '1', 07:08:58 INFO - 'TERM': 'linux', 07:08:58 INFO - 'TMOUT': '86400', 07:08:58 INFO - 'USER': 'cltbld', 07:08:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:58 INFO - '_': '/tools/buildbot/bin/python'} 07:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:08:59 INFO - Installing collected packages: mozsystemmonitor 07:08:59 INFO - Running setup.py install for mozsystemmonitor 07:08:59 INFO - Successfully installed mozsystemmonitor 07:08:59 INFO - Cleaning up... 07:08:59 INFO - Return code: 0 07:08:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:08:59 INFO - Running command: ['/builds/slave/test/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==2.5.1'] in /builds/slave/test/build 07:08:59 INFO - Copy/paste: /builds/slave/test/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==2.5.1 07:08:59 INFO - Using env: {'DISPLAY': ':0', 07:08:59 INFO - 'HOME': '/home/cltbld', 07:08:59 INFO - 'LANG': 'en_US.UTF-8', 07:08:59 INFO - 'LANGUAGE': 'en_US:en', 07:08:59 INFO - 'LOGNAME': 'cltbld', 07:08:59 INFO - 'MAIL': '/var/mail/cltbld', 07:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:59 INFO - 'MOZ_NO_REMOTE': '1', 07:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:59 INFO - 'NO_EM_RESTART': '1', 07:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:59 INFO - 'PWD': '/builds/slave/test', 07:08:59 INFO - 'SHELL': '/bin/bash', 07:08:59 INFO - 'SHLVL': '1', 07:08:59 INFO - 'TERM': 'linux', 07:08:59 INFO - 'TMOUT': '86400', 07:08:59 INFO - 'USER': 'cltbld', 07:08:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:59 INFO - '_': '/tools/buildbot/bin/python'} 07:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:59 INFO - Downloading/unpacking jsonschema==2.5.1 07:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:09:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:01 INFO - Downloading functools32-3.2.3-2.tar.gz 07:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:09:01 INFO - warning: no files found matching '*.txt' 07:09:01 INFO - no previously-included directories found matching 'build' 07:09:01 INFO - no previously-included directories found matching 'dist' 07:09:01 INFO - no previously-included directories found matching '.git*' 07:09:01 INFO - Installing collected packages: jsonschema, functools32 07:09:01 INFO - Running setup.py install for functools32 07:09:01 INFO - warning: no files found matching '*.txt' 07:09:01 INFO - no previously-included directories found matching 'build' 07:09:01 INFO - no previously-included directories found matching 'dist' 07:09:01 INFO - no previously-included directories found matching '.git*' 07:09:01 INFO - Successfully installed jsonschema functools32 07:09:01 INFO - Cleaning up... 07:09:01 INFO - Return code: 0 07:09:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:09:01 INFO - Running command: ['/builds/slave/test/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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:09:01 INFO - Copy/paste: /builds/slave/test/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 functools32==3.2.3-2 07:09:01 INFO - Using env: {'DISPLAY': ':0', 07:09:01 INFO - 'HOME': '/home/cltbld', 07:09:01 INFO - 'LANG': 'en_US.UTF-8', 07:09:01 INFO - 'LANGUAGE': 'en_US:en', 07:09:01 INFO - 'LOGNAME': 'cltbld', 07:09:01 INFO - 'MAIL': '/var/mail/cltbld', 07:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:01 INFO - 'MOZ_NO_REMOTE': '1', 07:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:01 INFO - 'NO_EM_RESTART': '1', 07:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:01 INFO - 'PWD': '/builds/slave/test', 07:09:01 INFO - 'SHELL': '/bin/bash', 07:09:01 INFO - 'SHLVL': '1', 07:09:01 INFO - 'TERM': 'linux', 07:09:01 INFO - 'TMOUT': '86400', 07:09:01 INFO - 'USER': 'cltbld', 07:09:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:01 INFO - '_': '/tools/buildbot/bin/python'} 07:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:09:01 INFO - Cleaning up... 07:09:01 INFO - Return code: 0 07:09:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:09:01 INFO - Running command: ['/builds/slave/test/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/build 07:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:09:01 INFO - Using env: {'DISPLAY': ':0', 07:09:01 INFO - 'HOME': '/home/cltbld', 07:09:01 INFO - 'LANG': 'en_US.UTF-8', 07:09:01 INFO - 'LANGUAGE': 'en_US:en', 07:09:01 INFO - 'LOGNAME': 'cltbld', 07:09:01 INFO - 'MAIL': '/var/mail/cltbld', 07:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:01 INFO - 'MOZ_NO_REMOTE': '1', 07:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:01 INFO - 'NO_EM_RESTART': '1', 07:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:01 INFO - 'PWD': '/builds/slave/test', 07:09:01 INFO - 'SHELL': '/bin/bash', 07:09:01 INFO - 'SHLVL': '1', 07:09:01 INFO - 'TERM': 'linux', 07:09:01 INFO - 'TMOUT': '86400', 07:09:01 INFO - 'USER': 'cltbld', 07:09:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:01 INFO - '_': '/tools/buildbot/bin/python'} 07:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:02 INFO - Downloading blobuploader-1.2.4.tar.gz 07:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:09:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:09:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:09: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 07:09: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 07:09: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 07:09: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 07:09: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 07:09: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 07:09:03 INFO - Downloading docopt-0.6.1.tar.gz 07:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:09:03 INFO - Installing collected packages: blobuploader, requests, docopt 07:09:03 INFO - Running setup.py install for blobuploader 07:09:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:09:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:09:03 INFO - Running setup.py install for requests 07:09:03 INFO - Running setup.py install for docopt 07:09:04 INFO - Successfully installed blobuploader requests docopt 07:09:04 INFO - Cleaning up... 07:09:04 INFO - Return code: 0 07:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 07:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:09:04 INFO - Using env: {'DISPLAY': ':0', 07:09:04 INFO - 'HOME': '/home/cltbld', 07:09:04 INFO - 'LANG': 'en_US.UTF-8', 07:09:04 INFO - 'LANGUAGE': 'en_US:en', 07:09:04 INFO - 'LOGNAME': 'cltbld', 07:09:04 INFO - 'MAIL': '/var/mail/cltbld', 07:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:04 INFO - 'MOZ_NO_REMOTE': '1', 07:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:04 INFO - 'NO_EM_RESTART': '1', 07:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:04 INFO - 'PWD': '/builds/slave/test', 07:09:04 INFO - 'SHELL': '/bin/bash', 07:09:04 INFO - 'SHLVL': '1', 07:09:04 INFO - 'TERM': 'linux', 07:09:04 INFO - 'TMOUT': '86400', 07:09:04 INFO - 'USER': 'cltbld', 07:09:04 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:04 INFO - '_': '/tools/buildbot/bin/python'} 07:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:04 INFO - Running setup.py (path:/tmp/pip-oB4CqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:04 INFO - Running setup.py (path:/tmp/pip-GyF9zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:04 INFO - Running setup.py (path:/tmp/pip-llKXx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:04 INFO - Running setup.py (path:/tmp/pip-2n108z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:04 INFO - Running setup.py (path:/tmp/pip-R7BPcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:04 INFO - Running setup.py (path:/tmp/pip-_hMcNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:04 INFO - Running setup.py (path:/tmp/pip-P8GYSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:04 INFO - Running setup.py (path:/tmp/pip-lSxYWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:04 INFO - Running setup.py (path:/tmp/pip-Cjmy17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:04 INFO - Running setup.py (path:/tmp/pip-7fdB3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:05 INFO - Running setup.py (path:/tmp/pip-wOyLm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:05 INFO - Running setup.py (path:/tmp/pip-2FIjj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:05 INFO - Running setup.py (path:/tmp/pip-R2XOlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:05 INFO - Running setup.py (path:/tmp/pip-fvWysL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:05 INFO - Running setup.py (path:/tmp/pip-fYZuls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:05 INFO - Running setup.py (path:/tmp/pip-bipVsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:05 INFO - Running setup.py (path:/tmp/pip-SOR49n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:09:05 INFO - Running setup.py install for manifestparser 07:09:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:09:05 INFO - Running setup.py install for mozcrash 07:09:05 INFO - Running setup.py install for mozdebug 07:09:05 INFO - Running setup.py install for mozdevice 07:09:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:09:05 INFO - Running setup.py install for mozfile 07:09:06 INFO - Running setup.py install for mozhttpd 07:09:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for mozinfo 07:09:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for mozInstall 07:09:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for mozleak 07:09:06 INFO - Running setup.py install for mozlog 07:09:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for moznetwork 07:09:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for mozprocess 07:09:06 INFO - Running setup.py install for mozprofile 07:09:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for mozrunner 07:09:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:09:06 INFO - Running setup.py install for mozscreenshot 07:09:07 INFO - Running setup.py install for moztest 07:09:07 INFO - Running setup.py install for mozversion 07:09:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:09:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:09:07 INFO - Cleaning up... 07:09:07 INFO - Return code: 0 07:09:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:09:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 07:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:09:07 INFO - Using env: {'DISPLAY': ':0', 07:09:07 INFO - 'HOME': '/home/cltbld', 07:09:07 INFO - 'LANG': 'en_US.UTF-8', 07:09:07 INFO - 'LANGUAGE': 'en_US:en', 07:09:07 INFO - 'LOGNAME': 'cltbld', 07:09:07 INFO - 'MAIL': '/var/mail/cltbld', 07:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:07 INFO - 'MOZ_NO_REMOTE': '1', 07:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:07 INFO - 'NO_EM_RESTART': '1', 07:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:07 INFO - 'PWD': '/builds/slave/test', 07:09:07 INFO - 'SHELL': '/bin/bash', 07:09:07 INFO - 'SHLVL': '1', 07:09:07 INFO - 'TERM': 'linux', 07:09:07 INFO - 'TMOUT': '86400', 07:09:07 INFO - 'USER': 'cltbld', 07:09:07 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:07 INFO - '_': '/tools/buildbot/bin/python'} 07:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:07 INFO - Running setup.py (path:/tmp/pip-o2D9PM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:07 INFO - Running setup.py (path:/tmp/pip-j9E3Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:07 INFO - Running setup.py (path:/tmp/pip-A1Xb6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:07 INFO - Running setup.py (path:/tmp/pip-2aeo9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:07 INFO - Running setup.py (path:/tmp/pip-cmqWT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:07 INFO - Running setup.py (path:/tmp/pip-l3fcO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:07 INFO - Running setup.py (path:/tmp/pip-Xe8nSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:08 INFO - Running setup.py (path:/tmp/pip-A9tlRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:08 INFO - Running setup.py (path:/tmp/pip-xbZFhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:08 INFO - Running setup.py (path:/tmp/pip-nMYrcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:08 INFO - Running setup.py (path:/tmp/pip-PNvug6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:08 INFO - Running setup.py (path:/tmp/pip-URo9qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:08 INFO - Running setup.py (path:/tmp/pip-mbKWC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:08 INFO - Running setup.py (path:/tmp/pip-l6Nd5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:08 INFO - Running setup.py (path:/tmp/pip-HJv96w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:08 INFO - Running setup.py (path:/tmp/pip-fFO8JF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:08 INFO - Running setup.py (path:/tmp/pip-j1CnAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:09:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:09 INFO - Downloading blessings-1.6.tar.gz 07:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:09:09 INFO - Installing collected packages: blessings 07:09:09 INFO - Running setup.py install for blessings 07:09:10 INFO - Successfully installed blessings 07:09:10 INFO - Cleaning up... 07:09:10 INFO - Return code: 0 07:09:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:09:10 INFO - Reading from file tmpfile_stdout 07:09:10 INFO - Current package versions: 07:09:10 INFO - argparse == 1.2.1 07:09:10 INFO - blessings == 1.6 07:09:10 INFO - blobuploader == 1.2.4 07:09:10 INFO - docopt == 0.6.1 07:09:10 INFO - functools32 == 3.2.3-2 07:09:10 INFO - jsonschema == 2.5.1 07:09:10 INFO - manifestparser == 1.1 07:09:10 INFO - mozInstall == 1.12 07:09:10 INFO - mozcrash == 1.0 07:09:10 INFO - mozdebug == 0.1 07:09:10 INFO - mozdevice == 0.50 07:09:10 INFO - mozfile == 1.2 07:09:10 INFO - mozhttpd == 0.7 07:09:10 INFO - mozinfo == 0.9 07:09:10 INFO - mozleak == 0.1 07:09:10 INFO - mozlog == 3.4 07:09:10 INFO - moznetwork == 0.27 07:09:10 INFO - mozprocess == 0.25 07:09:10 INFO - mozprofile == 0.28 07:09:10 INFO - mozrunner == 6.13 07:09:10 INFO - mozscreenshot == 0.1 07:09:10 INFO - mozsystemmonitor == 0.3 07:09:10 INFO - moztest == 0.8 07:09:10 INFO - mozversion == 1.4 07:09:10 INFO - psutil == 3.1.1 07:09:10 INFO - requests == 1.2.3 07:09:10 INFO - wsgiref == 0.1.2 07:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 07:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:09:10 INFO - Using env: {'DISPLAY': ':0', 07:09:10 INFO - 'HOME': '/home/cltbld', 07:09:10 INFO - 'LANG': 'en_US.UTF-8', 07:09:10 INFO - 'LANGUAGE': 'en_US:en', 07:09:10 INFO - 'LOGNAME': 'cltbld', 07:09:10 INFO - 'MAIL': '/var/mail/cltbld', 07:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:10 INFO - 'MOZ_NO_REMOTE': '1', 07:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:10 INFO - 'NO_EM_RESTART': '1', 07:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:10 INFO - 'PWD': '/builds/slave/test', 07:09:10 INFO - 'SHELL': '/bin/bash', 07:09:10 INFO - 'SHLVL': '1', 07:09:10 INFO - 'TERM': 'linux', 07:09:10 INFO - 'TMOUT': '86400', 07:09:10 INFO - 'USER': 'cltbld', 07:09:10 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:10 INFO - '_': '/tools/buildbot/bin/python'} 07:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:09:10 INFO - Cleaning up... 07:09:10 INFO - Return code: 0 07:09:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0xd241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd61a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:09:10 INFO - Running command: ['/builds/slave/test/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/build 07:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:09:10 INFO - Using env: {'DISPLAY': ':0', 07:09:10 INFO - 'HOME': '/home/cltbld', 07:09:10 INFO - 'LANG': 'en_US.UTF-8', 07:09:10 INFO - 'LANGUAGE': 'en_US:en', 07:09:10 INFO - 'LOGNAME': 'cltbld', 07:09:10 INFO - 'MAIL': '/var/mail/cltbld', 07:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:10 INFO - 'MOZ_NO_REMOTE': '1', 07:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:10 INFO - 'NO_EM_RESTART': '1', 07:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:10 INFO - 'PWD': '/builds/slave/test', 07:09:10 INFO - 'SHELL': '/bin/bash', 07:09:10 INFO - 'SHLVL': '1', 07:09:10 INFO - 'TERM': 'linux', 07:09:10 INFO - 'TMOUT': '86400', 07:09:10 INFO - 'USER': 'cltbld', 07:09:10 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:10 INFO - '_': '/tools/buildbot/bin/python'} 07:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:09:10 INFO - Cleaning up... 07:09:10 INFO - Return code: 0 07:09:10 INFO - Running post-action listener: _resource_record_post_action 07:09:10 INFO - Running post-action listener: _start_resource_monitoring 07:09:10 INFO - Starting resource monitoring. 07:09:10 INFO - [mozharness: 2017-05-05 14:09:10.778188Z] Finished create-virtualenv step (success) 07:09:10 INFO - [mozharness: 2017-05-05 14:09:10.778699Z] Running install step. 07:09:10 INFO - Running pre-action listener: _resource_record_pre_action 07:09:10 INFO - Running main action method: install 07:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:09:10 INFO - Reading from file tmpfile_stdout 07:09:10 INFO - Detecting whether we're running mozinstall >=1.0... 07:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:09:11 INFO - Reading from file tmpfile_stdout 07:09:11 INFO - Output received: 07:09:11 INFO - Usage: mozinstall [options] installer 07:09:11 INFO - Options: 07:09:11 INFO - -h, --help show this help message and exit 07:09:11 INFO - -d DEST, --destination=DEST 07:09:11 INFO - Directory to install application into. [default: 07:09:11 INFO - "/builds/slave/test"] 07:09:11 INFO - --app=APP Application being installed. [default: firefox] 07:09:11 INFO - mkdir: /builds/slave/test/build/application 07:09:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:09:27 INFO - Reading from file tmpfile_stdout 07:09:27 INFO - Output received: 07:09:27 INFO - /builds/slave/test/build/application/firefox/firefox 07:09:27 INFO - Running post-action listener: _resource_record_post_action 07:09:27 INFO - [mozharness: 2017-05-05 14:09:27.248219Z] Finished install step (success) 07:09:27 INFO - [mozharness: 2017-05-05 14:09:27.248369Z] Running run-tests step. 07:09:27 INFO - Running pre-action listener: _resource_record_pre_action 07:09:27 INFO - Running main action method: run_tests 07:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:09:27 INFO - Python 2.7.3 07:09:27 INFO - Return code: 0 07:09:27 INFO - grabbing minidump binary from tooltool 07:09:27 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:27 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbf400>, '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 0xfc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc0840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:09:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:09:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:09:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:09:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:09:27 INFO - Return code: 0 07:09:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:09:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:09:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:09:27 INFO - ENV: RUST_BACKTRACE is now 1 07:09:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:09:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:09:27 INFO - Using env: {'DISPLAY': ':0', 07:09:27 INFO - 'HOME': '/home/cltbld', 07:09:27 INFO - 'LANG': 'en_US.UTF-8', 07:09:27 INFO - 'LANGUAGE': 'en_US:en', 07:09:27 INFO - 'LOGNAME': 'cltbld', 07:09:27 INFO - 'MAIL': '/var/mail/cltbld', 07:09:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:09:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:27 INFO - 'MOZ_NO_REMOTE': '1', 07:09:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:27 INFO - 'NO_EM_RESTART': '1', 07:09:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:27 INFO - 'PWD': '/builds/slave/test', 07:09:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:09:27 INFO - 'RUST_BACKTRACE': '1', 07:09:27 INFO - 'SHELL': '/bin/bash', 07:09:27 INFO - 'SHLVL': '1', 07:09:27 INFO - 'TERM': 'linux', 07:09:27 INFO - 'TMOUT': '86400', 07:09:27 INFO - 'USER': 'cltbld', 07:09:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189', 07:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:27 INFO - '_': '/tools/buildbot/bin/python'} 07:09:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:09:27 INFO - mozversion application_buildid: 20170505131936 07:09:27 INFO - mozversion application_changeset: 8872ad4d52b6b517dc9d2a9f62c5c75ceda18790 07:09:27 INFO - mozversion application_display_name: Nightly 07:09:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:09:27 INFO - mozversion application_name: Firefox 07:09:27 INFO - mozversion application_remotingname: firefox 07:09:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:09:27 INFO - mozversion application_vendor: Mozilla 07:09:27 INFO - mozversion application_version: 55.0a1 07:09:27 INFO - mozversion platform_buildid: 20170505131936 07:09:27 INFO - mozversion platform_changeset: 8872ad4d52b6b517dc9d2a9f62c5c75ceda18790 07:09:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:09:27 INFO - mozversion platform_version: 55.0a1 07:09:27 INFO - using testdate: 1493993367 07:09:27 INFO - actual date: 1493993367 07:09:27 INFO - starting webserver on 'localhost:50621' 07:09:27 INFO - SUITE-START | Running 2 tests 07:09:27 INFO - TEST-START | dromaeo_css 07:09:27 INFO - operating with platform_type : linux_ 07:09:27 INFO - Initialising browser for dromaeo_css test... 07:09:27 INFO - TEST-INFO | started process 6459 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptFCFNQ/profile http://localhost:50621/getInfo.html) 07:09:31 INFO - PID 6459 | __metrics Screen width/height:1600/1200 07:09:31 INFO - PID 6459 | colorDepth:24 07:09:31 INFO - PID 6459 | Browser inner width/height: 1024/695 07:09:31 INFO - PID 6459 | __metrics 07:09:32 INFO - PID 6459 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:09:32 INFO - PID 6459 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:09:33 INFO - TEST-INFO | 6459: exit 0 07:09:33 INFO - Browser initialized. 07:09:33 INFO - Running cycle 1/1 for dromaeo_css test... 07:09:33 INFO - TEST-INFO | started process 6552 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptFCFNQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:09:45 INFO - PID 6552 | RSS: Main: 149512192 07:09:45 INFO - PID 6552 | 07:12:46 INFO - PID 6552 | Cycle 1(1): loaded http://localhost:50621/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50621/tests/dromaeo/cssquery-ext.html) 07:12:49 INFO - PID 6552 | RSS: Main: 380846080 07:12:49 INFO - PID 6552 | 07:15:51 INFO - PID 6552 | Cycle 1(1): loaded http://localhost:50621/tests/dromaeo/cssquery-ext.html (next: http://localhost:50621/tests/dromaeo/cssquery-jquery.html) 07:15:52 INFO - PID 6552 | RSS: Main: 222052352 07:15:52 INFO - PID 6552 | 07:18:53 INFO - PID 6552 | Cycle 1(1): loaded http://localhost:50621/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50621/tests/dromaeo/cssquery-mootools.html) 07:18:54 INFO - PID 6552 | RSS: Main: 208179200 07:18:54 INFO - PID 6552 | 07:21:56 INFO - PID 6552 | Cycle 1(1): loaded http://localhost:50621/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50621/tests/dromaeo/cssquery-prototype.html) 07:21:56 INFO - PID 6552 | RSS: Main: 185409536 07:21:56 INFO - PID 6552 | 07:24:59 INFO - PID 6552 | Cycle 1(1): loaded http://localhost:50621/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50621/tests/dromaeo/cssquery-yui.html) 07:25:00 INFO - PID 6552 | RSS: Main: 176283648 07:25:00 INFO - PID 6552 | 07:28:01 INFO - PID 6552 | Cycle 1(1): loaded http://localhost:50621/tests/dromaeo/cssquery-yui.html (next: http://localhost:50621/tests/dromaeo/cssquery-dojo.html) 07:28:02 INFO - PID 6552 | RSS: Main: 272535552 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | __start_tp_report 07:28:02 INFO - PID 6552 | _x_x_mozilla_page_load 07:28:02 INFO - PID 6552 | _x_x_mozilla_page_load_details 07:28:02 INFO - PID 6552 | |i|pagename|runs| 07:28:02 INFO - PID 6552 | |0;dojo.html;3314.3205642843204;3315.9502607460886;3319.618243901952;3326.567546218991;3373.2747459296247;5617.41017193195;5741.598088133832;5757.798477053304;5803.27459067616;5820.359760426355;5675.772969081242;5702.486776190142;5766.711664416784;5770.047942089561;5833.941660583393;4760.143174228638;5022.19644856823;5110.718910459923;5142.382914050319;5195.40252870919;5178.974105129488;5207.817726379578;5241.371035475749;5243.082460569404;5253.290805741233;5059.8988020239385;5083.949160508421;5129.563987061104;5144.948550514484;5164.8192313268855;5102.795888164469;5107.131787596118;5111.667741596784;5119.436861945183;5177.922331164998;5087.033463641897;5103.846884593431;5106.4382917879;5111.693298402108;5117.078925793356;5674.886502269973;5704.543636509069;5716.599838011341;5718.142278658193;5803.44867237616;6249.218847644044;6256.468200202989;6283.8114856554375;6286.119943207955;6286.119943207955;6301.306856245809;6303.275123360826;6305.369463053658;6315.778947736853;6319.905201421982;5664.51851592611;5732.914006289909;5828.76684932598;5878;5887.116932460165;6338.30278669346;6363.459105975951;6373.745050198033;6393.456556192683;6393.584417012879;6006.909896351558;6019.127226552213;6019.668918209453;6091.299500557404;6132.2947860996555;6178.196834411498;6196.194494715658;6199.039148931923;6201.0388389799655;6207.317195108534;6103.786367477117;6111.318268824403;6118.7714669719035;6173.259208894871;6176.474999624992;6209.19280493542;6223.533235007392;6259.780907668301;6262.655553944577;6263.843403914848;4762.523747625279;4764.475907650156;4769.523047695272;4804.95195048045;4806.158922188603;4171.290880550314;4211.915761684749;4229.323308270724;4237.131388065439;4247.320428731388;5756.136579513023;5775.8556036099435;5846.6784326862435;5864.44287792659;5872.383399742966;6233.470155036873;6245.437910588069;6247.375262473716;6248.343923888017;6250.124982502453;5611.326640803048;5802.100674395476;5805.651660900359;5809.3609702932645;5816.621919575214;5238.109521381374;5343.412224655285;5358.785648574013;5372.301600791872;5384.434634363335;6525.2169739631545;6541.6402097885075;6555.410013098844;6561.606303621798;6566.24488183865;6245.6252624842655;6255.687215639291;6263.311035785969;6265;6265.185525881606;6276.152719382825;6363.172787537591;6414.422701956846;6561.835954101186;6581.14445122131;6040.909386359121;6096.042928203331;6098.522998456991;6099.309783606499;6144.938550614436;6105.308121371012;6159.595405964238;6202.379762023762;6216.7824126155365;6223.097650840693;6008.489278411209;6049.848753781191;6069.0592958091565;6073.362296958932;6075.270967483753;6060.363661815446;6068.787592434243;6069.2110025696375;6070.180525628984;6079.392060793885;6032.668203248688;6040.486558642565;6041;6051.788187413419;6052.03167493199;6061.6666083365835;6085.878282434238;6086.847828804315;6106.053561697944;6120.479759220516;5930.199423077829;5982.341942386247;6020.886136064667;6032.849178770566;6032.90950635732 07:28:02 INFO - PID 6552 | |1;ext.html;808.4542933519874;839.6935118681679;1144.4792619358193;1155.3587758793876;1156.156006115535;35121.64878351216;37681.81159094204;37859.81070094646;37886.43170352447;37930.24139517208;39741;40114.99999999993;40154;40187;40383.79808100963;5489.780408783644;5627.662340259577;7254.637268136598;7374.004509391217;7387.224341444152;7182.317679820409;7358.705651773923;7368.852622947537;7369.226231245696;7372.078490188726;7444.925550744504;7477.439192060618;7480.962595187017;7483.476156669035;7488.475806693534;5566;5710.857228569277;7375.815604609874;7407.222241664602;7411.0736157980255;7154.391876690487;7206.9549915262;7313.744018959364;7315.561066335984;7323.560586364782;35737.64262357395;36128.819355903055;36151.4577281341;36154.63845361539;36180.09549761277;3383.9661603383897;3426.691597756214;3427.0232983599553;3428.0915557377316;3429.9657003429897;28841.567376489158;29004;29023.564646530318;29031.41937161266;29096.854515727497;3749;3780.1683629601443;3785.2429514097016;3796.0509872531866;3837.213371258913;187698;187760;188744;189148;189522;39605;40125;47451;47529.287060694114;47645.28532072021;49519;49582;50332.49667503278;50371.49628503668;50372.49627503678;13223.404946777418;13301.204337414325;13313;13315.800262996063;13326;10192.286539942133;10469.633562825416;10507.789844203073;10512.684619461428;10533.315334503233;13394.330283485786;13397.799033014513;13435.19388836673;13435.328233588478;13556.661083472993;5414.377346605112;5459.754311056012;5489.451054894478;5512.834614961477;5610.559406658117;1050.648032908984;1074.3875990685235;1076.9407682577532;1086.315621158665;1098.5880294889416;25726.74273257281;26013.869930650224;26041.348966275615;26073.73926260713;26087.73912260891;1158.2355645274079;1161.953521859116;1174.6065068202076;1176.4705882352976;1197.3653963399413;12933.482660693468;13608.261733952866;13625.931870340584;13703.862961370458;13714.382852771649;224340;224410;225020;225590.616140758;225899;52284.6471908765;52292.21561676578;52318.738406307726;52337.47662523402;52391.47608523866;1437.583100900727;1488.4418343121329;1496.2668292536578;1505.6160679026777;1525.5804653720315;24010.919508621806;25448.109316173846;30534.847325764116;31130;31291.374172516877;50637.24044139415;51317;51419;51446.48553514417;51456.48543514517;183859;184081;184531;184871.52949646697;185217;28783.280417989718;29309.12072637824;32711;32826.67173328236;33214;41350.586494135874;41378;42500.78749606232;42653;42827.57172428236;30771.230459278846;31194.688053120088;31974.840125800154;32125.518117228727;32441;31810.840945795124;31906.840465797522;32157.678423215468;32858.34283314368;32903.34193316072;37085.814570926974;37253.441198382585;37299;37322.813385932895;37352.252954941294;31924.840375797972;31926.521102183022;31962;32144.67855321417;32410.83794581012;23740.762592373856;26265.4746905057;26284.60573091367;26682.59976100399;26876.865615671795 07:28:02 INFO - PID 6552 | |2;jquery.html;434.103576115322;435.2774394505122;436.3716248602013;453.7141600791501;453.7391000174898;18658.34695785646;19673.016349182537;20196.89901550494;20360.08379622917;20422.387328380155;21680;21978.670319945213;22238.444038899037;22253.888730556366;22343.888280558574;5414.675119492833;5605.27131472908;5617.01702202115;5618.213450116987;5619.831405057835;5407.242985981966;5420.349558053039;5434.755436005389;5438.428964958682;5498.780048798043;5470.64440811348;5482.259894914196;5502.449755024486;5510.6969116698565;5511.889762204753;5282.683039017652;5376.435474275204;5416.783328666848;5418.756155972991;5507.972460137694;4902.3872015998;4924.926126108111;4925.630577706686;4926.605871530269;4933.333999910002;25324.113656022135;25675.486490270276;25690.357741056436;25880.482390352277;25893;2152.2897443843494;2167.6098302305577;2184.3119417383473;2187.048633844283;2188.67169924511;16659.66680666392;17071.317147314094;17243.31026758928;17313.567160820956;17461;2207.6357401028813;2209.49181688211;2228.9219877304217;2258.7967082962614;2269.6822444857735;96251.51874240654;96396.36036396305;96978.51510742401;97739.51130244303;97847;12083.45624446902;12113.39433028345;12141.453634586465;12231;12647.241165530098;17679.823201768075;17750.733738993924;17751.556211094827;17759.55601109957;17766;8285.917140828635;8446.66213351459;8620;8673.392862499564;8690.826183476294;7627.351675107567;7633.2366763324335;7889.802754931173;7928;7936.127026027132;7674.42441816866;7914.129445760962;7996.440249182501;8015.879761803578;8036.397270204758;2156.4393257753095;2174.554216385637;2207.911683532641;2232.3860938242115;2271.829612779048;1058.5554067291755;1058.846467262258;1075.6020272499225;1079.740862193068;1082.09644946469;20894;20960.895195523924;21028.579428411347;21069.78930210709;21149.78850211478;1092.9398883061508;1094.5731164845718;1094.9671509854718;1096.1504833753904;1107.2083460325905;11000;11365;11413;11561.479733412016;11570.958613724804;455471;465037;468075;475689;477311;88033.22385169043;91516.08483915208;92561.53719231361;93435;94029.52985235167;1194.9223300485442;1198.7063169523522;1225.0383448992498;1226.2642414551199;1227.2575092069349;16309.836901630832;16815.579610509834;17603.471895843144;17611.88964746269;17678.204480798653;43184.78407607942;43595.56404436042;43928.56071439245;44043.779781102174;44730.10539789121;54065.45934540762;55174.44825551854;55449;55628.443715563946;57326;8807.987081485455;9023.548822558978;9026.729198124067;9149.124429491805;9152.22206112488;14381.568552943429;14533.636659083608;14612;14992.550223493312;15040.849591503946;10869;12784.488620455078;12888.677783055498;12953.222806631633;13100.60698179056;12366.752664946831;12926.095173337777;13048.282344471145;13262.071654984444;13463.192208467523;13335.66660833487;13336.733265334446;13375.866241337462;13450.192988420726;13489.39297731624;9321.673741419017;9409.623615055321;9417.62329506812;9430.245580353418;9488.193503552275;12617;12802.871971280169;13070.346482675637;13184;13277.535286264934 07:28:02 INFO - PID 6552 | |3;mootools.html;1181.061056460114;1333.9533116340926;1369.9657508562284;1380.9171449713012;1393.9233342166183;9526.571304291307;10971.890281097187;11348.716282092952;11352.829707554394;11356.772864542705;11342.659720208387;11426.942865285662;11448.599299024536;11458.083353331735;11462.025727813147;1910.522369407648;1976.3379267945256;2301.1140710826367;2343.742188359279;2344.6248600223967;2244.9663255051187;2323.0939933425893;2329.2779238436137;2333.439974406139;2337.2053501809382;2239.8656080635133;2260.1298500077505;2261.0955617752948;2262.5814224368464;2264.67162261472;2220.966685499719;2234.575430668168;2239.5632851593946;2240.8991595378247;2241.887905604713;2192.473806286496;2194.0675213034556;2195.3523710505438;2199.5930752810623;2205.4596623827106;11258.662240132728;11413.942930285295;11437.313761174357;11441.828372574335;11444.599439019677;5747.48272655463;5958.493528050121;5961;5961.01643228871;5961.8807623847715;3657.7256705747177;3829.80850957451;3964.4846169998004;4008.8151957183404;4043.514778226602;1348.9123206991612;1371.2732251906505;1376.3806287170712;1432.8065711129072;1433.9713205735827;16868.156592170344;16881.83118168815;16884.24020919049;16892.662146756997;16905.49283521484;4960.330355402047;5145.61407894402;5244.6328756987405;5276.65701729394;5331.333583302087;14086.71826563463;14389.208593527457;14404.135751854923;14468.27658617065;14490.130592164503;6328.398802113792;6337.081123237105;6338.112664227012;6344.968275158687;6347.904781428282;4731.976340118277;5138.794448222104;5829.854253643693;5838.941610583925;5841;4364.127174565099;4422.270325396338;4490.820367185342;4505.7521836298665;4514.751688657089;1355.349432272495;1389.9166050037031;1401.3833913078213;1401.5655146904492;1405.416752047909;835.703325319515;852.8976522817178;864.8572985457329;877.2499512916493;896.8878890138733;6218.440340369298;6403.071554624554;6470.741170353133;6505.316941721146;6566.4418524425;603.5925750118653;607.1984979826586;961.0869673809908;987.6543209876486;991.8413053911484;4038.8586399475876;4088.2232375848494;4107.712460127822;4137.648299894543;4138.420621113047;14912.850871491146;15411.61470963235;15524;15533.223338833013;15587.298571564306;14101.08342957725;14921.403143874124;14979.277383100865;14982.550523484313;14984.100953942798;9228.723138305862;9371.671991480265;9409.435433873852;9425.811483770287;9426.764330891645;6935.618540980295;7215;7217.133943926762;7238.710451582088;7271.381932535582;13668.31658417095;13702.725945480835;13808.654783630489;13864.861351386357;13867.791983120464;13920.72158556803;13971.860281397056;14015.299235038412;14166.504172353918;14170.433182672577;8727.91272087271;9039.548022598712;9081.364304498622;9541.618335266789;9770;11008.559657613927;11351.886481135083;11378.43107844621;11381.260218085798;11396.772064558496;9156.816863662823;9182.449053056838;9218.723438296862;9231.446113233227;9337.159655630754;9332.160105590257;9395.295352848427;9427.575759090994;9463.526823658927;9521.19069879068;8110.716124935599;8247.175282471944;8274.089850116363;8274.669013239403;8274.958625206835;5808.332041815245;5925.466707996301;5933.703314834327;5984.551158663205;6099.176611157437;5847.707614619337;5950.732217050157;5962.25471816023;6031.577789554689;6092 07:28:02 INFO - PID 6552 | |4;prototype.html;391.47583446164737;402.4968789013733;411.7482852129526;419.6328212813786;419.99160016799664;5718.22803921471;5741.224934633823;5749.166370876221;5756.481916627503;5781.913271300934;5454.672719636825;5626.156076588513;5630.465105814951;5636.887262254752;5662.037453632882;4298.935515967263;4336.934945975813;4345.8478953236645;4348.739075655455;4350.608445239928;4315.956840431602;4355.891102722426;4358.629516491102;4362.760048197348;4366.410534577839;4178.7283826551175;4298.398224248608;4321.848735294266;4330.740155590659;4337.240982828008;4286.97856510717;4290.699651024398;4312.180685669713;4312.28847240205;4328.935065974013;3784.545854497478;3787.9431808522895;3797.1076796952693;3797.905052373685;3820.1730719267543;5311.150215965427;5525.696086715229;5538.9169162462595;5564.720114373677;5572.3870374258795;4970.229614409773;5035.571976382012;5047.217681259411;5056.367954005749;5060.316857224265;5966.582339236255;5967.373425790316;5968.821380196197;5971.940280597182;5986.491148252404;4514.390557274759;4619.722816631013;4641.512641172696;4661.487236403993;4662.347271382009;5986.341502434772;6130.295016073164;6149.877002459926;6158.6612736299485;6292.39543916303;4753.857384278477;4779.832705855279;4801.087793319258;4801.327814106028;4810.350602668595;4558.49856515783;4681.976590117028;4771.260454629538;4857.684250523705;4881.487443818419;5331.946680533145;5336.573074154058;5338.386085600128;5345.342943687469;5352.812651557255;5903.6457812530525;6047.2743270806905;6059.7273122709585;6079.75680972765;6079.939200608026;1659.1621231278127;1684.5788552861784;1685.890417122883;1686.7180942483556;1689.873259505542;1193.236328749601;1195.5397172088808;1227.3127048852493;1228.6068458093325;1229.244014930811;5730.169125476783;5752.511036561939;5765.971170144123;5770.163326317745;5792.391798861143;6150.292716337589;6166.784162554289;6211.937880621226;6223;6236;5558.610897237155;5704.771809127588;5730.713464326767;5735.8852822944145;5738.110592858114;3220.404225218342;3234.741220702338;3236.4336241157703;3238;3238.7409007279834;6167.907481387782;6385.329540398284;6423.839404014843;6454.290028096905;6476.773312934025;6338.904916426257;6339.587926784744;6349.746010159542;6358.523110766619;6366.522510811707;6310.5267104967315;6438.8390290243115;6453.741850325934;6470.2559205691;6501.1548498694865;5902.498287645598;6163.907541386972;6189.381061893775;6208.4722798562625;6218.19163508741;5982.700864956648;5993.77127688814;6112;6123.142760013513;6128.712970276292;6465.028166409103;6466;6479.870402591828;6483.481321494175;6487.351264873663;4209.91580168401;4328.394024836462;4359.607635312837;4364.454443194601;4372.6939114261695;6202.41077097675;6232.626042437287;6238.6880655967925;6245.250569931637;6248.625082495065;5871.2073870027;6022.307434645072;6036.456718895321;6058.969705151622;6066.575339726177;6039.335673076046;6046.758129674764;6052.818415447544;6060.454559089703;6065.696715164136;6002.879942401215;6006.729697163551;6010.849728756816;6049.304330002106;6053;5896.764129434775;6019.428154325507;6023.789167379121;6041.456268935817;6056.606320589148;5843.357230704534;5908.143319218775;5909.7294081772625;5920.940790592212;5926.140709596998 07:28:02 INFO - PID 6552 | |5;yui.html;867.991320086799;876.7676565710084;882.3426547222318;894.8836651235339;901.265468643056;14826.258687065643;15471.071735695867;15502.534923952271;15507.767383489243;15512.612184695388;14569.125852448835;15512.922435387836;15599.220038998033;15617.531474055768;15637.530874073767;947.4504787223411;979.6571200079986;981.4307701533094;982.552938413024;982.773961988743;929.730378190324;986.4820968991265;991.1080027974845;991.2218908157098;992.5434300221907;1023.3706270643538;1027.3425007994852;1027.4143738069304;1028.9897101029005;1029.1766586730623;1021.6985989133224;1037.4294138223947;1037.6057098302597;1037.8754549454077;1038.5689938675441;760.3423039071164;763.3282711214096;763.3282711214096;764.6559048428229;764.7667461424263;15843.445479408281;15952.20238988046;16153.83846161535;16284.26720797567;16396.426125085563;18604.813951860444;18635.813641863544;18996.90501547497;19071.80928190728;19177;14295.785563216456;14707.705845883022;14819.481318153812;14862.925685371612;14882.10707357562;3388.7797293175863;3403.9489407658907;3409.5567576214935;3412.181076541612;3412.590489141319;92744;93213.53393233058;93258.5337073317;94567;94995;14190;14265.144091354552;14358.712825743427;14447.85552144486;14449.638759031108;26693.599596006075;26783.330416739347;26815.19554413371;27122.18633441;27155.72844271532;8083.434159608889;8145.51126932386;8193.180681931759;8193.549354785544;8196.303314218358;3951.4863067801;4057.69567282455;4060.1067765091634;4066.207089617496;4070.4504891839815;4563.247064234431;4604.976975115103;4608.5621865922685;4651.581357677757;4656.208444564364;811.5130921447203;824.9216324449168;827.3050637464559;829.1873963515668;836.9455985360933;726.7819654103748;739.8594267089236;752.3341842469454;763.9656215470317;771.1748429180723;11933.761324773455;12333.814992775115;12642.672519385516;12666.36668166588;12791.680207994874;736.8121129112042;744.3115118515352;747.3236720767605;755.5731011978214;760.5322726523259;5602.13166959122;5610.242617246701;5771.220885180447;5840.1239814027385;5901.557383196277;85867.14132858592;86101;86349.13650863536;88303.8187326881;89209.55395223112;75067.62466187634;75541;78188;78316.82524762134;78641.60679196568;59774.70112649496;66984.33015669878;67556.324436755;68324.65837670879;68445;12970.416331264927;13903.443862245396;13912;14029.508967186099;14032.368543415367;33147.33705325928;33558.66441335555;33789;34476.655233447345;34748.65251347555;39584;49272.75363623302;56117;56895.71552142213;57947.42052579589;16533.91733041327;16661.416850410176;16788;16869.662606748043;16987.7451838225;16138.67722645564;16170.595735106246;16174.757378639095;16373.754393684343;16496.505104846874;17465.12674366302;17498.650026999643;17520.912395438452;17660.911695441442;17819.910900445415;17023.233954472325;17257.65484690274;17271.222794974005;17436;17636.118194089988;17479.912600436917;17686.20412081434;17716.11419429049;17821.821781782015;18148.909255453636;12347.259164450163;12514.687132821753;12690.809637855255;12825.166364186298;12983.35083245815;13908.235047072103;14103.015486539385;14158.78761818553;14273.429062837371;14306.856931430968 07:28:02 INFO - PID 6552 | __end_tp_report 07:28:02 INFO - PID 6552 | __start_cc_report 07:28:02 INFO - PID 6552 | _x_x_mozilla_cycle_collect,2452 07:28:02 INFO - PID 6552 | __end_cc_report 07:28:02 INFO - PID 6552 | __startTimestamp1493994482136__endTimestamp 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | ------- Summary: start ------- 07:28:02 INFO - PID 6552 | Number of tests: 6 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | [#0] dojo.html Cycles:165 Average:5742.87 Median:6032.67 stddev:685.63 (11.4%) stddev-sans-first:660.73 07:28:02 INFO - PID 6552 | Values: 3314.3 3316.0 3319.6 3326.6 3373.3 5617.4 5741.6 5757.8 5803.3 5820.4 5675.8 5702.5 5766.7 5770.0 5833.9 4760.1 5022.2 5110.7 5142.4 5195.4 5179.0 5207.8 5241.4 5243.1 5253.3 5059.9 5083.9 5129.6 5144.9 5164.8 5102.8 5107.1 5111.7 5119.4 5177.9 5087.0 5103.8 5106.4 5111.7 5117.1 5674.9 5704.5 5716.6 5718.1 5803.4 6249.2 6256.5 6283.8 6286.1 6286.1 6301.3 6303.3 6305.4 6315.8 6319.9 5664.5 5732.9 5828.8 5878.0 5887.1 6338.3 6363.5 6373.7 6393.5 6393.6 6006.9 6019.1 6019.7 6091.3 6132.3 6178.2 6196.2 6199.0 6201.0 6207.3 6103.8 6111.3 6118.8 6173.3 6176.5 6209.2 6223.5 6259.8 6262.7 6263.8 4762.5 4764.5 4769.5 4805.0 4806.2 4171.3 4211.9 4229.3 4237.1 4247.3 5756.1 5775.9 5846.7 5864.4 5872.4 6233.5 6245.4 6247.4 6248.3 6250.1 5611.3 5802.1 5805.7 5809.4 5816.6 5238.1 5343.4 5358.8 5372.3 5384.4 6525.2 6541.6 6555.4 6561.6 6566.2 6245.6 6255.7 6263.3 6265.0 6265.2 6276.2 6363.2 6414.4 6561.8 6581.1 6040.9 6096.0 6098.5 6099.3 6144.9 6105.3 6159.6 6202.4 6216.8 6223.1 6008.5 6049.8 6069.1 6073.4 6075.3 6060.4 6068.8 6069.2 6070.2 6079.4 6032.7 6040.5 6041.0 6051.8 6052.0 6061.7 6085.9 6086.8 6106.1 6120.5 5930.2 5982.3 6020.9 6032.8 6032.9 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | [#1] ext.html Cycles:180 Average:36932.86 Median:32880.84 stddev:51924.32 (157.9%) stddev-sans-first:51999.13 07:28:02 INFO - PID 6552 | Values: 808.5 839.7 1144.5 1155.4 1156.2 35121.6 37681.8 37859.8 37886.4 37930.2 39741.0 40115.0 40154.0 40187.0 40383.8 5489.8 5627.7 7254.6 7374.0 7387.2 7182.3 7358.7 7368.9 7369.2 7372.1 7444.9 7477.4 7481.0 7483.5 7488.5 5566.0 5710.9 7375.8 7407.2 7411.1 7154.4 7207.0 7313.7 7315.6 7323.6 35737.6 36128.8 36151.5 36154.6 36180.1 3384.0 3426.7 3427.0 3428.1 3430.0 28841.6 29004.0 29023.6 29031.4 29096.9 3749.0 3780.2 3785.2 3796.1 3837.2 187698.0 187760.0 188744.0 189148.0 189522.0 39605.0 40125.0 47451.0 47529.3 47645.3 49519.0 49582.0 50332.5 50371.5 50372.5 13223.4 13301.2 13313.0 13315.8 13326.0 10192.3 10469.6 10507.8 10512.7 10533.3 13394.3 13397.8 13435.2 13435.3 13556.7 5414.4 5459.8 5489.5 5512.8 5610.6 1050.6 1074.4 1076.9 1086.3 1098.6 25726.7 26013.9 26041.3 26073.7 26087.7 1158.2 1162.0 1174.6 1176.5 1197.4 12933.5 13608.3 13625.9 13703.9 13714.4 224340.0 224410.0 225020.0 225590.6 225899.0 52284.6 52292.2 52318.7 52337.5 52391.5 1437.6 1488.4 1496.3 1505.6 1525.6 24010.9 25448.1 30534.8 31130.0 31291.4 50637.2 51317.0 51419.0 51446.5 51456.5 183859.0 184081.0 184531.0 184871.5 185217.0 28783.3 29309.1 32711.0 32826.7 33214.0 41350.6 41378.0 42500.8 42653.0 42827.6 30771.2 31194.7 31974.8 32125.5 32441.0 31810.8 31906.8 32157.7 32858.3 32903.3 37085.8 37253.4 37299.0 37322.8 37352.3 31924.8 31926.5 31962.0 32144.7 32410.8 23740.8 26265.5 26284.6 26682.6 26876.9 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | [#2] jquery.html Cycles:180 Average:29659.01 Median:12288.14 stddev:77529.79 (630.9%) stddev-sans-first:77716.22 07:28:02 INFO - PID 6552 | Values: 434.1 435.3 436.4 453.7 453.7 18658.3 19673.0 20196.9 20360.1 20422.4 21680.0 21978.7 22238.4 22253.9 22343.9 5414.7 5605.3 5617.0 5618.2 5619.8 5407.2 5420.3 5434.8 5438.4 5498.8 5470.6 5482.3 5502.4 5510.7 5511.9 5282.7 5376.4 5416.8 5418.8 5508.0 4902.4 4924.9 4925.6 4926.6 4933.3 25324.1 25675.5 25690.4 25880.5 25893.0 2152.3 2167.6 2184.3 2187.0 2188.7 16659.7 17071.3 17243.3 17313.6 17461.0 2207.6 2209.5 2228.9 2258.8 2269.7 96251.5 96396.4 96978.5 97739.5 97847.0 12083.5 12113.4 12141.5 12231.0 12647.2 17679.8 17750.7 17751.6 17759.6 17766.0 8285.9 8446.7 8620.0 8673.4 8690.8 7627.4 7633.2 7889.8 7928.0 7936.1 7674.4 7914.1 7996.4 8015.9 8036.4 2156.4 2174.6 2207.9 2232.4 2271.8 1058.6 1058.8 1075.6 1079.7 1082.1 20894.0 20960.9 21028.6 21069.8 21149.8 1092.9 1094.6 1095.0 1096.2 1107.2 11000.0 11365.0 11413.0 11561.5 11571.0 455471.0 465037.0 468075.0 475689.0 477311.0 88033.2 91516.1 92561.5 93435.0 94029.5 1194.9 1198.7 1225.0 1226.3 1227.3 16309.8 16815.6 17603.5 17611.9 17678.2 43184.8 43595.6 43928.6 44043.8 44730.1 54065.5 55174.4 55449.0 55628.4 57326.0 8808.0 9023.5 9026.7 9149.1 9152.2 14381.6 14533.6 14612.0 14992.6 15040.8 10869.0 12784.5 12888.7 12953.2 13100.6 12366.8 12926.1 13048.3 13262.1 13463.2 13335.7 13336.7 13375.9 13450.2 13489.4 9321.7 9409.6 9417.6 9430.2 9488.2 12617.0 12802.9 13070.3 13184.0 13277.5 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | [#3] mootools.html Cycles:180 Average:7158.96 Median:3897.15 stddev:4707.77 (120.8%) stddev-sans-first:4699.55 07:28:02 INFO - PID 6552 | Values: 1181.1 1334.0 1370.0 1380.9 1393.9 9526.6 10971.9 11348.7 11352.8 11356.8 11342.7 11426.9 11448.6 11458.1 11462.0 1910.5 1976.3 2301.1 2343.7 2344.6 2245.0 2323.1 2329.3 2333.4 2337.2 2239.9 2260.1 2261.1 2262.6 2264.7 2221.0 2234.6 2239.6 2240.9 2241.9 2192.5 2194.1 2195.4 2199.6 2205.5 11258.7 11413.9 11437.3 11441.8 11444.6 5747.5 5958.5 5961.0 5961.0 5961.9 3657.7 3829.8 3964.5 4008.8 4043.5 1348.9 1371.3 1376.4 1432.8 1434.0 16868.2 16881.8 16884.2 16892.7 16905.5 4960.3 5145.6 5244.6 5276.7 5331.3 14086.7 14389.2 14404.1 14468.3 14490.1 6328.4 6337.1 6338.1 6345.0 6347.9 4732.0 5138.8 5829.9 5838.9 5841.0 4364.1 4422.3 4490.8 4505.8 4514.8 1355.3 1389.9 1401.4 1401.6 1405.4 835.7 852.9 864.9 877.2 896.9 6218.4 6403.1 6470.7 6505.3 6566.4 603.6 607.2 961.1 987.7 991.8 4038.9 4088.2 4107.7 4137.6 4138.4 14912.9 15411.6 15524.0 15533.2 15587.3 14101.1 14921.4 14979.3 14982.6 14984.1 9228.7 9371.7 9409.4 9425.8 9426.8 6935.6 7215.0 7217.1 7238.7 7271.4 13668.3 13702.7 13808.7 13864.9 13867.8 13920.7 13971.9 14015.3 14166.5 14170.4 8727.9 9039.5 9081.4 9541.6 9770.0 11008.6 11351.9 11378.4 11381.3 11396.8 9156.8 9182.4 9218.7 9231.4 9337.2 9332.2 9395.3 9427.6 9463.5 9521.2 8110.7 8247.2 8274.1 8274.7 8275.0 5808.3 5925.5 5933.7 5984.6 6099.2 5847.7 5950.7 5962.3 6031.6 6092.0 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | [#4] prototype.html Cycles:180 Average:5089.51 Median:5737.00 stddev:1470.35 (25.6%) stddev-sans-first:1431.57 07:28:02 INFO - PID 6552 | Values: 391.5 402.5 411.7 419.6 420.0 5718.2 5741.2 5749.2 5756.5 5781.9 5454.7 5626.2 5630.5 5636.9 5662.0 4298.9 4336.9 4345.8 4348.7 4350.6 4316.0 4355.9 4358.6 4362.8 4366.4 4178.7 4298.4 4321.8 4330.7 4337.2 4287.0 4290.7 4312.2 4312.3 4328.9 3784.5 3787.9 3797.1 3797.9 3820.2 5311.2 5525.7 5538.9 5564.7 5572.4 4970.2 5035.6 5047.2 5056.4 5060.3 5966.6 5967.4 5968.8 5971.9 5986.5 4514.4 4619.7 4641.5 4661.5 4662.3 5986.3 6130.3 6149.9 6158.7 6292.4 4753.9 4779.8 4801.1 4801.3 4810.4 4558.5 4682.0 4771.3 4857.7 4881.5 5331.9 5336.6 5338.4 5345.3 5352.8 5903.6 6047.3 6059.7 6079.8 6079.9 1659.2 1684.6 1685.9 1686.7 1689.9 1193.2 1195.5 1227.3 1228.6 1229.2 5730.2 5752.5 5766.0 5770.2 5792.4 6150.3 6166.8 6211.9 6223.0 6236.0 5558.6 5704.8 5730.7 5735.9 5738.1 3220.4 3234.7 3236.4 3238.0 3238.7 6167.9 6385.3 6423.8 6454.3 6476.8 6338.9 6339.6 6349.7 6358.5 6366.5 6310.5 6438.8 6453.7 6470.3 6501.2 5902.5 6163.9 6189.4 6208.5 6218.2 5982.7 5993.8 6112.0 6123.1 6128.7 6465.0 6466.0 6479.9 6483.5 6487.4 4209.9 4328.4 4359.6 4364.5 4372.7 6202.4 6232.6 6238.7 6245.3 6248.6 5871.2 6022.3 6036.5 6059.0 6066.6 6039.3 6046.8 6052.8 6060.5 6065.7 6002.9 6006.7 6010.8 6049.3 6053.0 5896.8 6019.4 6023.8 6041.5 6056.6 5843.4 5908.1 5909.7 5920.9 5926.1 07:28:02 INFO - PID 6552 | 07:28:02 INFO - PID 6552 | [#5] yui.html Cycles:180 Average:19574.84 Median:33353.00 stddev:24459.23 (73.3%) stddev-sans-first:24487.51 07:28:02 INFO - PID 6552 | Values: 868.0 876.8 882.3 894.9 901.3 14826.3 15471.1 15502.5 15507.8 15512.6 14569.1 15512.9 15599.2 15617.5 15637.5 947.5 979.7 981.4 982.6 982.8 929.7 986.5 991.1 991.2 992.5 1023.4 1027.3 1027.4 1029.0 1029.2 1021.7 1037.4 1037.6 1037.9 1038.6 760.3 763.3 763.3 764.7 764.8 15843.4 15952.2 16153.8 16284.3 16396.4 18604.8 18635.8 18996.9 19071.8 19177.0 14295.8 14707.7 14819.5 14862.9 14882.1 3388.8 3403.9 3409.6 3412.2 3412.6 92744.0 93213.5 93258.5 94567.0 94995.0 14190.0 14265.1 14358.7 14447.9 14449.6 26693.6 26783.3 26815.2 27122.2 27155.7 8083.4 8145.5 8193.2 8193.5 8196.3 3951.5 4057.7 4060.1 4066.2 4070.5 4563.2 4605.0 4608.6 4651.6 4656.2 811.5 824.9 827.3 829.2 836.9 726.8 739.9 752.3 764.0 771.2 11933.8 12333.8 12642.7 12666.4 12791.7 736.8 744.3 747.3 755.6 760.5 5602.1 5610.2 5771.2 5840.1 5901.6 85867.1 86101.0 86349.1 88303.8 89209.6 75067.6 75541.0 78188.0 78316.8 78641.6 59774.7 66984.3 67556.3 68324.7 68445.0 12970.4 13903.4 13912.0 14029.5 14032.4 33147.3 33558.7 33789.0 34476.7 34748.7 39584.0 49272.8 56117.0 56895.7 57947.4 16533.9 16661.4 16788.0 16869.7 16987.7 16138.7 16170.6 16174.8 16373.8 16496.5 17465.1 17498.7 17520.9 17660.9 17819.9 17023.2 17257.7 17271.2 17436.0 17636.1 17479.9 17686.2 17716.1 17821.8 18148.9 12347.3 12514.7 12690.8 12825.2 12983.4 13908.2 14103.0 14158.8 14273.4 14306.9 07:28:02 INFO - PID 6552 | -------- Summary: end -------- 07:28:02 INFO - PID 6552 | 07:28:02 INFO - TEST-INFO | 6552: exit 0 07:28:02 INFO - TEST-OK | dromaeo_css | took 1115321ms 07:28:02 INFO - TEST-START | kraken 07:28:02 INFO - operating with platform_type : linux_ 07:28:02 INFO - Initialising browser for kraken test... 07:28:02 INFO - TEST-INFO | started process 6885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBwFFgx/profile http://localhost:50621/getInfo.html) 07:28:06 INFO - PID 6885 | __metrics Screen width/height:1600/1200 07:28:06 INFO - PID 6885 | colorDepth:24 07:28:06 INFO - PID 6885 | Browser inner width/height: 1024/695 07:28:06 INFO - PID 6885 | __metrics 07:28:07 INFO - PID 6885 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:28:07 INFO - PID 6885 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:28:08 INFO - TEST-INFO | 6885: exit 0 07:28:08 INFO - Browser initialized. 07:28:08 INFO - Running cycle 1/1 for kraken test... 07:28:08 INFO - TEST-INFO | started process 6981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBwFFgx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:28:20 INFO - PID 6981 | RSS: Main: 150577152 07:28:20 INFO - PID 6981 | 07:28:22 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50621/tests/kraken/driver.html?testName=audio-beat-detection) 07:28:23 INFO - PID 6981 | RSS: Main: 222818304 07:28:23 INFO - PID 6981 | 07:28:26 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50621/tests/kraken/driver.html?testName=audio-dft) 07:28:26 INFO - PID 6981 | RSS: Main: 184225792 07:28:26 INFO - PID 6981 | 07:28:29 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50621/tests/kraken/driver.html?testName=audio-fft) 07:28:30 INFO - PID 6981 | RSS: Main: 264998912 07:28:30 INFO - PID 6981 | 07:28:32 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50621/tests/kraken/driver.html?testName=audio-oscillator) 07:28:33 INFO - PID 6981 | RSS: Main: 176861184 07:28:33 INFO - PID 6981 | 07:28:35 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50621/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:28:35 INFO - PID 6981 | RSS: Main: 179519488 07:28:35 INFO - PID 6981 | 07:28:38 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50621/tests/kraken/driver.html?testName=imaging-darkroom) 07:28:39 INFO - PID 6981 | RSS: Main: 240312320 07:28:39 INFO - PID 6981 | 07:28:42 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50621/tests/kraken/driver.html?testName=imaging-desaturate) 07:28:43 INFO - PID 6981 | RSS: Main: 260521984 07:28:43 INFO - PID 6981 | 07:28:45 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50621/tests/kraken/driver.html?testName=json-parse-financial) 07:28:46 INFO - PID 6981 | RSS: Main: 275894272 07:28:46 INFO - PID 6981 | 07:28:47 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50621/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:28:48 INFO - PID 6981 | RSS: Main: 203177984 07:28:48 INFO - PID 6981 | 07:28:50 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:28:50 INFO - PID 6981 | RSS: Main: 209068032 07:28:50 INFO - PID 6981 | 07:28:52 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:28:53 INFO - PID 6981 | RSS: Main: 218894336 07:28:53 INFO - PID 6981 | 07:28:55 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:28:56 INFO - PID 6981 | RSS: Main: 226480128 07:28:56 INFO - PID 6981 | 07:28:59 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:29:00 INFO - PID 6981 | RSS: Main: 222085120 07:29:00 INFO - PID 6981 | 07:29:01 INFO - PID 6981 | Cycle 1(1): loaded http://localhost:50621/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50621/tests/kraken/driver.html?testName=ai-astar) 07:29:02 INFO - PID 6981 | RSS: Main: 272322560 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | __start_tp_report 07:29:02 INFO - PID 6981 | _x_x_mozilla_page_load 07:29:02 INFO - PID 6981 | _x_x_mozilla_page_load_details 07:29:02 INFO - PID 6981 | |i|pagename|runs| 07:29:02 INFO - PID 6981 | |0;ai-astar;107;110;109;109;110;112;108;108;110;110 07:29:02 INFO - PID 6981 | |1;audio-beat-detection;142;115;117;168;117;121;115;115;141;120 07:29:02 INFO - PID 6981 | |2;audio-dft;162;161;161;161;161;162;165;162;163;162 07:29:02 INFO - PID 6981 | |3;audio-fft;73;74;73;104;75;75;74;75;101;74 07:29:02 INFO - PID 6981 | |4;audio-oscillator;82;82;106;81;81;80;93;82;81;116 07:29:02 INFO - PID 6981 | |5;imaging-gaussian-blur;113;113;113;112;112;113;113;112;113;113 07:29:02 INFO - PID 6981 | |6;imaging-darkroom;146;146;147;146;147;146;148;147;147;146 07:29:02 INFO - PID 6981 | |7;imaging-desaturate;95;94;94;94;94;94;94;93;94;94 07:29:02 INFO - PID 6981 | |8;json-parse-financial;47;45;44;45;45;47;46;45;46;45 07:29:02 INFO - PID 6981 | |9;json-stringify-tinderbox;56;57;57;57;57;55;57;57;57;57 07:29:02 INFO - PID 6981 | |10;stanford-crypto-aes;82;81;80;80;81;80;79;81;81;80 07:29:02 INFO - PID 6981 | |11;stanford-crypto-ccm;127;125;135;127;125;123;125;122;182;132 07:29:02 INFO - PID 6981 | |12;stanford-crypto-pbkdf2;181;189;183;184;180;178;179;181;181;184 07:29:02 INFO - PID 6981 | |13;stanford-crypto-sha256-iterative;65;66;66;67;64;63;65;66;64;65 07:29:02 INFO - PID 6981 | __end_tp_report 07:29:02 INFO - PID 6981 | __start_cc_report 07:29:02 INFO - PID 6981 | _x_x_mozilla_cycle_collect,1665 07:29:02 INFO - PID 6981 | __end_cc_report 07:29:02 INFO - PID 6981 | __startTimestamp1493994542074__endTimestamp 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | ------- Summary: start ------- 07:29:02 INFO - PID 6981 | Number of tests: 14 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:1.42 (1.3%) stddev-sans-first:1.24 07:29:02 INFO - PID 6981 | Values: 107.0 110.0 109.0 109.0 110.0 112.0 108.0 108.0 110.0 110.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:120.50 stddev:17.70 (14.7%) stddev-sans-first:17.93 07:29:02 INFO - PID 6981 | Values: 142.0 115.0 117.0 168.0 117.0 121.0 115.0 115.0 141.0 120.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 07:29:02 INFO - PID 6981 | Values: 162.0 161.0 161.0 161.0 161.0 162.0 165.0 162.0 163.0 162.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#3] audio-fft Cycles:10 Average:79.80 Median:74.00 stddev:12.01 (16.2%) stddev-sans-first:12.48 07:29:02 INFO - PID 6981 | Values: 73.0 74.0 73.0 104.0 75.0 75.0 74.0 75.0 101.0 74.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:81.50 stddev:12.69 (15.6%) stddev-sans-first:13.25 07:29:02 INFO - PID 6981 | Values: 82.0 82.0 106.0 81.0 81.0 80.0 93.0 82.0 81.0 116.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:29:02 INFO - PID 6981 | Values: 113.0 113.0 113.0 112.0 112.0 113.0 113.0 112.0 113.0 113.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 07:29:02 INFO - PID 6981 | Values: 146.0 146.0 147.0 146.0 147.0 146.0 148.0 147.0 147.0 146.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 07:29:02 INFO - PID 6981 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#8] json-parse-financial Cycles:10 Average:45.50 Median:45.50 stddev:0.97 (2.1%) stddev-sans-first:0.87 07:29:02 INFO - PID 6981 | Values: 47.0 45.0 44.0 45.0 45.0 47.0 46.0 45.0 46.0 45.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:57.00 stddev:0.67 (1.2%) stddev-sans-first:0.67 07:29:02 INFO - PID 6981 | Values: 56.0 57.0 57.0 57.0 57.0 55.0 57.0 57.0 57.0 57.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:81.00 stddev:0.85 (1.0%) stddev-sans-first:0.71 07:29:02 INFO - PID 6981 | Values: 82.0 81.0 80.0 80.0 81.0 80.0 79.0 81.0 81.0 80.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:127.00 stddev:17.91 (14.1%) stddev-sans-first:18.89 07:29:02 INFO - PID 6981 | Values: 127.0 125.0 135.0 127.0 125.0 123.0 125.0 122.0 182.0 132.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:182.00 stddev:3.16 (1.7%) stddev-sans-first:3.33 07:29:02 INFO - PID 6981 | Values: 181.0 189.0 183.0 184.0 180.0 178.0 179.0 181.0 181.0 184.0 07:29:02 INFO - PID 6981 | 07:29:02 INFO - PID 6981 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.20 (1.8%) stddev-sans-first:1.27 07:29:02 INFO - PID 6981 | Values: 65.0 66.0 66.0 67.0 64.0 63.0 65.0 66.0 64.0 65.0 07:29:02 INFO - PID 6981 | -------- Summary: end -------- 07:29:02 INFO - PID 6981 | 07:29:03 INFO - TEST-INFO | 6981: exit 0 07:29:03 INFO - TEST-OK | kraken | took 60416ms 07:29:03 INFO - SUITE-END | took 1175s 07:29:03 INFO - Completed test suite (00:19:36) 07:29:03 INFO - javascript benchmark 07:29:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7630.151682382902, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.3205642843204, 3315.9502607460886, 3319.618243901952, 3326.567546218991, 3373.2747459296247, 5617.41017193195, 5741.598088133832, 5757.798477053304, 5803.27459067616, 5820.359760426355, 5675.772969081242, 5702.486776190142, 5766.711664416784, 5770.047942089561, 5833.941660583393, 4760.143174228638, 5022.19644856823, 5110.718910459923, 5142.382914050319, 5195.40252870919, 5178.974105129488, 5207.817726379578, 5241.371035475749, 5243.082460569404, 5253.290805741233, 5059.8988020239385, 5083.949160508421, 5129.563987061104, 5144.948550514484, 5164.8192313268855, 5102.795888164469, 5107.131787596118, 5111.667741596784, 5119.436861945183, 5177.922331164998, 5087.033463641897, 5103.846884593431, 5106.4382917879, 5111.693298402108, 5117.078925793356, 5674.886502269973, 5704.543636509069, 5716.599838011341, 5718.142278658193, 5803.44867237616, 6249.218847644044, 6256.468200202989, 6283.8114856554375, 6286.119943207955, 6286.119943207955, 6301.306856245809, 6303.275123360826, 6305.369463053658, 6315.778947736853, 6319.905201421982, 5664.51851592611, 5732.914006289909, 5828.76684932598, 5878.0, 5887.116932460165, 6338.30278669346, 6363.459105975951, 6373.745050198033, 6393.456556192683, 6393.584417012879, 6006.909896351558, 6019.127226552213, 6019.668918209453, 6091.299500557404, 6132.2947860996555, 6178.196834411498, 6196.194494715658, 6199.039148931923, 6201.0388389799655, 6207.317195108534, 6103.786367477117, 6111.318268824403, 6118.7714669719035, 6173.259208894871, 6176.474999624992, 6209.19280493542, 6223.533235007392, 6259.780907668301, 6262.655553944577, 6263.843403914848, 4762.523747625279, 4764.475907650156, 4769.523047695272, 4804.95195048045, 4806.158922188603, 4171.290880550314, 4211.915761684749, 4229.323308270724, 4237.131388065439, 4247.320428731388, 5756.136579513023, 5775.8556036099435, 5846.6784326862435, 5864.44287792659, 5872.383399742966, 6233.470155036873, 6245.437910588069, 6247.375262473716, 6248.343923888017, 6250.124982502453, 5611.326640803048, 5802.100674395476, 5805.651660900359, 5809.3609702932645, 5816.621919575214, 5238.109521381374, 5343.412224655285, 5358.785648574013, 5372.301600791872, 5384.434634363335, 6525.2169739631545, 6541.6402097885075, 6555.410013098844, 6561.606303621798, 6566.24488183865, 6245.6252624842655, 6255.687215639291, 6263.311035785969, 6265.0, 6265.185525881606, 6276.152719382825, 6363.172787537591, 6414.422701956846, 6561.835954101186, 6581.14445122131, 6040.909386359121, 6096.042928203331, 6098.522998456991, 6099.309783606499, 6144.938550614436, 6105.308121371012, 6159.595405964238, 6202.379762023762, 6216.7824126155365, 6223.097650840693, 6008.489278411209, 6049.848753781191, 6069.0592958091565, 6073.362296958932, 6075.270967483753, 6060.363661815446, 6068.787592434243, 6069.2110025696375, 6070.180525628984, 6079.392060793885, 6032.668203248688, 6040.486558642565, 6041.0, 6051.788187413419, 6052.03167493199, 6061.6666083365835, 6085.878282434238, 6086.847828804315, 6106.053561697944, 6120.479759220516, 5930.199423077829, 5982.341942386247, 6020.886136064667, 6032.849178770566, 6032.90950635732], "value": 5694.805178495556, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.4542933519874, 839.6935118681679, 1144.4792619358193, 1155.3587758793876, 1156.156006115535, 35121.64878351216, 37681.81159094204, 37859.81070094646, 37886.43170352447, 37930.24139517208, 39741.0, 40114.99999999993, 40154.0, 40187.0, 40383.79808100963, 5489.780408783644, 5627.662340259577, 7254.637268136598, 7374.004509391217, 7387.224341444152, 7182.317679820409, 7358.705651773923, 7368.852622947537, 7369.226231245696, 7372.078490188726, 7444.925550744504, 7477.439192060618, 7480.962595187017, 7483.476156669035, 7488.475806693534, 5566.0, 5710.857228569277, 7375.815604609874, 7407.222241664602, 7411.0736157980255, 7154.391876690487, 7206.9549915262, 7313.744018959364, 7315.561066335984, 7323.560586364782, 35737.64262357395, 36128.819355903055, 36151.4577281341, 36154.63845361539, 36180.09549761277, 3383.9661603383897, 3426.691597756214, 3427.0232983599553, 3428.0915557377316, 3429.9657003429897, 28841.567376489158, 29004.0, 29023.564646530318, 29031.41937161266, 29096.854515727497, 3749.0, 3780.1683629601443, 3785.2429514097016, 3796.0509872531866, 3837.213371258913, 187698.0, 187760.0, 188744.0, 189148.0, 189522.0, 39605.0, 40125.0, 47451.0, 47529.287060694114, 47645.28532072021, 49519.0, 49582.0, 50332.49667503278, 50371.49628503668, 50372.49627503678, 13223.404946777418, 13301.204337414325, 13313.0, 13315.800262996063, 13326.0, 10192.286539942133, 10469.633562825416, 10507.789844203073, 10512.684619461428, 10533.315334503233, 13394.330283485786, 13397.799033014513, 13435.19388836673, 13435.328233588478, 13556.661083472993, 5414.377346605112, 5459.754311056012, 5489.451054894478, 5512.834614961477, 5610.559406658117, 1050.648032908984, 1074.3875990685235, 1076.9407682577532, 1086.315621158665, 1098.5880294889416, 25726.74273257281, 26013.869930650224, 26041.348966275615, 26073.73926260713, 26087.73912260891, 1158.2355645274079, 1161.953521859116, 1174.6065068202076, 1176.4705882352976, 1197.3653963399413, 12933.482660693468, 13608.261733952866, 13625.931870340584, 13703.862961370458, 13714.382852771649, 224340.0, 224410.0, 225020.0, 225590.616140758, 225899.0, 52284.6471908765, 52292.21561676578, 52318.738406307726, 52337.47662523402, 52391.47608523866, 1437.583100900727, 1488.4418343121329, 1496.2668292536578, 1505.6160679026777, 1525.5804653720315, 24010.919508621806, 25448.109316173846, 30534.847325764116, 31130.0, 31291.374172516877, 50637.24044139415, 51317.0, 51419.0, 51446.48553514417, 51456.48543514517, 183859.0, 184081.0, 184531.0, 184871.52949646697, 185217.0, 28783.280417989718, 29309.12072637824, 32711.0, 32826.67173328236, 33214.0, 41350.586494135874, 41378.0, 42500.78749606232, 42653.0, 42827.57172428236, 30771.230459278846, 31194.688053120088, 31974.840125800154, 32125.518117228727, 32441.0, 31810.840945795124, 31906.840465797522, 32157.678423215468, 32858.34283314368, 32903.34193316072, 37085.814570926974, 37253.441198382585, 37299.0, 37322.813385932895, 37352.252954941294, 31924.840375797972, 31926.521102183022, 31962.0, 32144.67855321417, 32410.83794581012, 23740.762592373856, 26265.4746905057, 26284.60573091367, 26682.59976100399, 26876.865615671795], "value": 16835.912757489677, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.103576115322, 435.2774394505122, 436.3716248602013, 453.7141600791501, 453.7391000174898, 18658.34695785646, 19673.016349182537, 20196.89901550494, 20360.08379622917, 20422.387328380155, 21680.0, 21978.670319945213, 22238.444038899037, 22253.888730556366, 22343.888280558574, 5414.675119492833, 5605.27131472908, 5617.01702202115, 5618.213450116987, 5619.831405057835, 5407.242985981966, 5420.349558053039, 5434.755436005389, 5438.428964958682, 5498.780048798043, 5470.64440811348, 5482.259894914196, 5502.449755024486, 5510.6969116698565, 5511.889762204753, 5282.683039017652, 5376.435474275204, 5416.783328666848, 5418.756155972991, 5507.972460137694, 4902.3872015998, 4924.926126108111, 4925.630577706686, 4926.605871530269, 4933.333999910002, 25324.113656022135, 25675.486490270276, 25690.357741056436, 25880.482390352277, 25893.0, 2152.2897443843494, 2167.6098302305577, 2184.3119417383473, 2187.048633844283, 2188.67169924511, 16659.66680666392, 17071.317147314094, 17243.31026758928, 17313.567160820956, 17461.0, 2207.6357401028813, 2209.49181688211, 2228.9219877304217, 2258.7967082962614, 2269.6822444857735, 96251.51874240654, 96396.36036396305, 96978.51510742401, 97739.51130244303, 97847.0, 12083.45624446902, 12113.39433028345, 12141.453634586465, 12231.0, 12647.241165530098, 17679.823201768075, 17750.733738993924, 17751.556211094827, 17759.55601109957, 17766.0, 8285.917140828635, 8446.66213351459, 8620.0, 8673.392862499564, 8690.826183476294, 7627.351675107567, 7633.2366763324335, 7889.802754931173, 7928.0, 7936.127026027132, 7674.42441816866, 7914.129445760962, 7996.440249182501, 8015.879761803578, 8036.397270204758, 2156.4393257753095, 2174.554216385637, 2207.911683532641, 2232.3860938242115, 2271.829612779048, 1058.5554067291755, 1058.846467262258, 1075.6020272499225, 1079.740862193068, 1082.09644946469, 20894.0, 20960.895195523924, 21028.579428411347, 21069.78930210709, 21149.78850211478, 1092.9398883061508, 1094.5731164845718, 1094.9671509854718, 1096.1504833753904, 1107.2083460325905, 11000.0, 11365.0, 11413.0, 11561.479733412016, 11570.958613724804, 455471.0, 465037.0, 468075.0, 475689.0, 477311.0, 88033.22385169043, 91516.08483915208, 92561.53719231361, 93435.0, 94029.52985235167, 1194.9223300485442, 1198.7063169523522, 1225.0383448992498, 1226.2642414551199, 1227.2575092069349, 16309.836901630832, 16815.579610509834, 17603.471895843144, 17611.88964746269, 17678.204480798653, 43184.78407607942, 43595.56404436042, 43928.56071439245, 44043.779781102174, 44730.10539789121, 54065.45934540762, 55174.44825551854, 55449.0, 55628.443715563946, 57326.0, 8807.987081485455, 9023.548822558978, 9026.729198124067, 9149.124429491805, 9152.22206112488, 14381.568552943429, 14533.636659083608, 14612.0, 14992.550223493312, 15040.849591503946, 10869.0, 12784.488620455078, 12888.677783055498, 12953.222806631633, 13100.60698179056, 12366.752664946831, 12926.095173337777, 13048.282344471145, 13262.071654984444, 13463.192208467523, 13335.66660833487, 13336.733265334446, 13375.866241337462, 13450.192988420726, 13489.39297731624, 9321.673741419017, 9409.623615055321, 9417.62329506812, 9430.245580353418, 9488.193503552275, 12617.0, 12802.871971280169, 13070.346482675637, 13184.0, 13277.535286264934], "value": 9999.205080990061, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1181.061056460114, 1333.9533116340926, 1369.9657508562284, 1380.9171449713012, 1393.9233342166183, 9526.571304291307, 10971.890281097187, 11348.716282092952, 11352.829707554394, 11356.772864542705, 11342.659720208387, 11426.942865285662, 11448.599299024536, 11458.083353331735, 11462.025727813147, 1910.522369407648, 1976.3379267945256, 2301.1140710826367, 2343.742188359279, 2344.6248600223967, 2244.9663255051187, 2323.0939933425893, 2329.2779238436137, 2333.439974406139, 2337.2053501809382, 2239.8656080635133, 2260.1298500077505, 2261.0955617752948, 2262.5814224368464, 2264.67162261472, 2220.966685499719, 2234.575430668168, 2239.5632851593946, 2240.8991595378247, 2241.887905604713, 2192.473806286496, 2194.0675213034556, 2195.3523710505438, 2199.5930752810623, 2205.4596623827106, 11258.662240132728, 11413.942930285295, 11437.313761174357, 11441.828372574335, 11444.599439019677, 5747.48272655463, 5958.493528050121, 5961.0, 5961.01643228871, 5961.8807623847715, 3657.7256705747177, 3829.80850957451, 3964.4846169998004, 4008.8151957183404, 4043.514778226602, 1348.9123206991612, 1371.2732251906505, 1376.3806287170712, 1432.8065711129072, 1433.9713205735827, 16868.156592170344, 16881.83118168815, 16884.24020919049, 16892.662146756997, 16905.49283521484, 4960.330355402047, 5145.61407894402, 5244.6328756987405, 5276.65701729394, 5331.333583302087, 14086.71826563463, 14389.208593527457, 14404.135751854923, 14468.27658617065, 14490.130592164503, 6328.398802113792, 6337.081123237105, 6338.112664227012, 6344.968275158687, 6347.904781428282, 4731.976340118277, 5138.794448222104, 5829.854253643693, 5838.941610583925, 5841.0, 4364.127174565099, 4422.270325396338, 4490.820367185342, 4505.7521836298665, 4514.751688657089, 1355.349432272495, 1389.9166050037031, 1401.3833913078213, 1401.5655146904492, 1405.416752047909, 835.703325319515, 852.8976522817178, 864.8572985457329, 877.2499512916493, 896.8878890138733, 6218.440340369298, 6403.071554624554, 6470.741170353133, 6505.316941721146, 6566.4418524425, 603.5925750118653, 607.1984979826586, 961.0869673809908, 987.6543209876486, 991.8413053911484, 4038.8586399475876, 4088.2232375848494, 4107.712460127822, 4137.648299894543, 4138.420621113047, 14912.850871491146, 15411.61470963235, 15524.0, 15533.223338833013, 15587.298571564306, 14101.08342957725, 14921.403143874124, 14979.277383100865, 14982.550523484313, 14984.100953942798, 9228.723138305862, 9371.671991480265, 9409.435433873852, 9425.811483770287, 9426.764330891645, 6935.618540980295, 7215.0, 7217.133943926762, 7238.710451582088, 7271.381932535582, 13668.31658417095, 13702.725945480835, 13808.654783630489, 13864.861351386357, 13867.791983120464, 13920.72158556803, 13971.860281397056, 14015.299235038412, 14166.504172353918, 14170.433182672577, 8727.91272087271, 9039.548022598712, 9081.364304498622, 9541.618335266789, 9770.0, 11008.559657613927, 11351.886481135083, 11378.43107844621, 11381.260218085798, 11396.772064558496, 9156.816863662823, 9182.449053056838, 9218.723438296862, 9231.446113233227, 9337.159655630754, 9332.160105590257, 9395.295352848427, 9427.575759090994, 9463.526823658927, 9521.19069879068, 8110.716124935599, 8247.175282471944, 8274.089850116363, 8274.669013239403, 8274.958625206835, 5808.332041815245, 5925.466707996301, 5933.703314834327, 5984.551158663205, 6099.176611157437, 5847.707614619337, 5950.732217050157, 5962.25471816023, 6031.577789554689, 6092.0], "value": 5322.6360840972, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.47583446164737, 402.4968789013733, 411.7482852129526, 419.6328212813786, 419.99160016799664, 5718.22803921471, 5741.224934633823, 5749.166370876221, 5756.481916627503, 5781.913271300934, 5454.672719636825, 5626.156076588513, 5630.465105814951, 5636.887262254752, 5662.037453632882, 4298.935515967263, 4336.934945975813, 4345.8478953236645, 4348.739075655455, 4350.608445239928, 4315.956840431602, 4355.891102722426, 4358.629516491102, 4362.760048197348, 4366.410534577839, 4178.7283826551175, 4298.398224248608, 4321.848735294266, 4330.740155590659, 4337.240982828008, 4286.97856510717, 4290.699651024398, 4312.180685669713, 4312.28847240205, 4328.935065974013, 3784.545854497478, 3787.9431808522895, 3797.1076796952693, 3797.905052373685, 3820.1730719267543, 5311.150215965427, 5525.696086715229, 5538.9169162462595, 5564.720114373677, 5572.3870374258795, 4970.229614409773, 5035.571976382012, 5047.217681259411, 5056.367954005749, 5060.316857224265, 5966.582339236255, 5967.373425790316, 5968.821380196197, 5971.940280597182, 5986.491148252404, 4514.390557274759, 4619.722816631013, 4641.512641172696, 4661.487236403993, 4662.347271382009, 5986.341502434772, 6130.295016073164, 6149.877002459926, 6158.6612736299485, 6292.39543916303, 4753.857384278477, 4779.832705855279, 4801.087793319258, 4801.327814106028, 4810.350602668595, 4558.49856515783, 4681.976590117028, 4771.260454629538, 4857.684250523705, 4881.487443818419, 5331.946680533145, 5336.573074154058, 5338.386085600128, 5345.342943687469, 5352.812651557255, 5903.6457812530525, 6047.2743270806905, 6059.7273122709585, 6079.75680972765, 6079.939200608026, 1659.1621231278127, 1684.5788552861784, 1685.890417122883, 1686.7180942483556, 1689.873259505542, 1193.236328749601, 1195.5397172088808, 1227.3127048852493, 1228.6068458093325, 1229.244014930811, 5730.169125476783, 5752.511036561939, 5765.971170144123, 5770.163326317745, 5792.391798861143, 6150.292716337589, 6166.784162554289, 6211.937880621226, 6223.0, 6236.0, 5558.610897237155, 5704.771809127588, 5730.713464326767, 5735.8852822944145, 5738.110592858114, 3220.404225218342, 3234.741220702338, 3236.4336241157703, 3238.0, 3238.7409007279834, 6167.907481387782, 6385.329540398284, 6423.839404014843, 6454.290028096905, 6476.773312934025, 6338.904916426257, 6339.587926784744, 6349.746010159542, 6358.523110766619, 6366.522510811707, 6310.5267104967315, 6438.8390290243115, 6453.741850325934, 6470.2559205691, 6501.1548498694865, 5902.498287645598, 6163.907541386972, 6189.381061893775, 6208.4722798562625, 6218.19163508741, 5982.700864956648, 5993.77127688814, 6112.0, 6123.142760013513, 6128.712970276292, 6465.028166409103, 6466.0, 6479.870402591828, 6483.481321494175, 6487.351264873663, 4209.91580168401, 4328.394024836462, 4359.607635312837, 4364.454443194601, 4372.6939114261695, 6202.41077097675, 6232.626042437287, 6238.6880655967925, 6245.250569931637, 6248.625082495065, 5871.2073870027, 6022.307434645072, 6036.456718895321, 6058.969705151622, 6066.575339726177, 6039.335673076046, 6046.758129674764, 6052.818415447544, 6060.454559089703, 6065.696715164136, 6002.879942401215, 6006.729697163551, 6010.849728756816, 6049.304330002106, 6053.0, 5896.764129434775, 6019.428154325507, 6023.789167379121, 6041.456268935817, 6056.606320589148, 5843.357230704534, 5908.143319218775, 5909.7294081772625, 5920.940790592212, 5926.140709596998], "value": 4649.886536028561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [867.991320086799, 876.7676565710084, 882.3426547222318, 894.8836651235339, 901.265468643056, 14826.258687065643, 15471.071735695867, 15502.534923952271, 15507.767383489243, 15512.612184695388, 14569.125852448835, 15512.922435387836, 15599.220038998033, 15617.531474055768, 15637.530874073767, 947.4504787223411, 979.6571200079986, 981.4307701533094, 982.552938413024, 982.773961988743, 929.730378190324, 986.4820968991265, 991.1080027974845, 991.2218908157098, 992.5434300221907, 1023.3706270643538, 1027.3425007994852, 1027.4143738069304, 1028.9897101029005, 1029.1766586730623, 1021.6985989133224, 1037.4294138223947, 1037.6057098302597, 1037.8754549454077, 1038.5689938675441, 760.3423039071164, 763.3282711214096, 763.3282711214096, 764.6559048428229, 764.7667461424263, 15843.445479408281, 15952.20238988046, 16153.83846161535, 16284.26720797567, 16396.426125085563, 18604.813951860444, 18635.813641863544, 18996.90501547497, 19071.80928190728, 19177.0, 14295.785563216456, 14707.705845883022, 14819.481318153812, 14862.925685371612, 14882.10707357562, 3388.7797293175863, 3403.9489407658907, 3409.5567576214935, 3412.181076541612, 3412.590489141319, 92744.0, 93213.53393233058, 93258.5337073317, 94567.0, 94995.0, 14190.0, 14265.144091354552, 14358.712825743427, 14447.85552144486, 14449.638759031108, 26693.599596006075, 26783.330416739347, 26815.19554413371, 27122.18633441, 27155.72844271532, 8083.434159608889, 8145.51126932386, 8193.180681931759, 8193.549354785544, 8196.303314218358, 3951.4863067801, 4057.69567282455, 4060.1067765091634, 4066.207089617496, 4070.4504891839815, 4563.247064234431, 4604.976975115103, 4608.5621865922685, 4651.581357677757, 4656.208444564364, 811.5130921447203, 824.9216324449168, 827.3050637464559, 829.1873963515668, 836.9455985360933, 726.7819654103748, 739.8594267089236, 752.3341842469454, 763.9656215470317, 771.1748429180723, 11933.761324773455, 12333.814992775115, 12642.672519385516, 12666.36668166588, 12791.680207994874, 736.8121129112042, 744.3115118515352, 747.3236720767605, 755.5731011978214, 760.5322726523259, 5602.13166959122, 5610.242617246701, 5771.220885180447, 5840.1239814027385, 5901.557383196277, 85867.14132858592, 86101.0, 86349.13650863536, 88303.8187326881, 89209.55395223112, 75067.62466187634, 75541.0, 78188.0, 78316.82524762134, 78641.60679196568, 59774.70112649496, 66984.33015669878, 67556.324436755, 68324.65837670879, 68445.0, 12970.416331264927, 13903.443862245396, 13912.0, 14029.508967186099, 14032.368543415367, 33147.33705325928, 33558.66441335555, 33789.0, 34476.655233447345, 34748.65251347555, 39584.0, 49272.75363623302, 56117.0, 56895.71552142213, 57947.42052579589, 16533.91733041327, 16661.416850410176, 16788.0, 16869.662606748043, 16987.7451838225, 16138.67722645564, 16170.595735106246, 16174.757378639095, 16373.754393684343, 16496.505104846874, 17465.12674366302, 17498.650026999643, 17520.912395438452, 17660.911695441442, 17819.910900445415, 17023.233954472325, 17257.65484690274, 17271.222794974005, 17436.0, 17636.118194089988, 17479.912600436917, 17686.20412081434, 17716.11419429049, 17821.821781782015, 18148.909255453636, 12347.259164450163, 12514.687132821753, 12690.809637855255, 12825.166364186298, 12983.35083245815, 13908.235047072103, 14103.015486539385, 14158.78761818553, 14273.429062837371, 14306.856931430968], "value": 8316.061584389652, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 109.0, 109.0, 110.0, 112.0, 108.0, 108.0, 110.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 117.0, 168.0, 117.0, 121.0, 115.0, 115.0, 141.0, 120.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 165.0, 162.0, 163.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 104.0, 75.0, 75.0, 74.0, 75.0, 101.0, 74.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 106.0, 81.0, 81.0, 80.0, 93.0, 82.0, 81.0, 116.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 148.0, 147.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 45.0, 44.0, 45.0, 45.0, 47.0, 46.0, 45.0, 46.0, 45.0], "value": 45.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 57.0, 57.0, 55.0, 57.0, 57.0, 57.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 80.0, 81.0, 80.0, 79.0, 81.0, 81.0, 80.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 125.0, 135.0, 127.0, 125.0, 123.0, 125.0, 122.0, 182.0, 132.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 189.0, 183.0, 184.0, 180.0, 178.0, 179.0, 181.0, 181.0, 184.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 64.0, 63.0, 65.0, 66.0, 64.0, 65.0], "value": 65.1, "unit": "score"}]}]} 07:29:03 INFO - Return code: 0 07:29:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:29:03 INFO - # TBPL SUCCESS # 07:29:03 INFO - Running post-action listener: _resource_record_post_action 07:29:03 INFO - [mozharness: 2017-05-05 14:29:03.889376Z] Finished run-tests step (success) 07:29:03 INFO - Running post-run listener: _resource_record_post_run 07:29:03 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 359063552; Read time: 172; Write time: 228888 07:29:03 INFO - TinderboxPrint: CPU usage
12.9% 07:29:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 172 07:29:03 INFO - TinderboxPrint: I/O write bytes / time
359,063,552 / 228,888 07:29:03 INFO - TinderboxPrint: CPU idle
8,296.2 (87.1%) 07:29:03 INFO - TinderboxPrint: CPU user
1,183.7 (12.4%) 07:29:03 INFO - TinderboxPrint: Swap in / out
0 / 0 07:29:03 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 30703616; Read time: 0; Write time: 6056 07:29:03 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 281698304; Read time: 0; Write time: 205456 07:29:04 INFO - Running post-run listener: _upload_blobber_files 07:29:04 INFO - Blob upload gear active. 07:29:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:29:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:29:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:29:04 INFO - (blobuploader) - INFO - Open directory for files ... 07:29:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:29:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:29:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:29:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:29:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:29:05 INFO - (blobuploader) - INFO - Done attempting. 07:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:29:09 INFO - (blobuploader) - INFO - Done attempting. 07:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:29:10 INFO - (blobuploader) - INFO - Done attempting. 07:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:29:10 INFO - (blobuploader) - INFO - Done attempting. 07:29:10 INFO - (blobuploader) - INFO - Iteration through files over. 07:29:10 INFO - Return code: 0 07:29:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:29:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:29:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a529eac420652023c75b99fcccae05c1ab085a964807eb873a29df1c1333f7589a89d426bed5d2005ce174f4aa79441d64537afff41441e0b18754ebaaa7a3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088c9322ce6e6711dbe7470cf3c2b0db29d15330adc765395ab7e19a21f9d542697f6c68a7c0c7cdb5c205c49c53ea257d8483e5717c84d51b9688a623214c29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6044c5aa3e09d195e128793695ff5a1bf83265ecb4e48f555e1bd65069e12541235ab17a074c079ce02bdb813c93dc664fa58fee76c5f9ca9f8d801e4225e35f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ea09507b90130251bd26109766002c70628365006c66b5c14cd581bcf1587c41fd667732fd3743f97eda373adbac3c339c17471e82fd6b532ee5c2d3a453d2a"} 07:29:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:29:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:29:10 INFO - Contents: 07:29:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a529eac420652023c75b99fcccae05c1ab085a964807eb873a29df1c1333f7589a89d426bed5d2005ce174f4aa79441d64537afff41441e0b18754ebaaa7a3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088c9322ce6e6711dbe7470cf3c2b0db29d15330adc765395ab7e19a21f9d542697f6c68a7c0c7cdb5c205c49c53ea257d8483e5717c84d51b9688a623214c29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6044c5aa3e09d195e128793695ff5a1bf83265ecb4e48f555e1bd65069e12541235ab17a074c079ce02bdb813c93dc664fa58fee76c5f9ca9f8d801e4225e35f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ea09507b90130251bd26109766002c70628365006c66b5c14cd581bcf1587c41fd667732fd3743f97eda373adbac3c339c17471e82fd6b532ee5c2d3a453d2a"} 07:29:10 INFO - Running post-run listener: copy_logs_to_upload_dir 07:29:10 INFO - Copying logs to upload dir... 07:29:10 INFO - mkdir: /builds/slave/test/build/upload/logs 07:29:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.717857 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-05 07:29:10.295411) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 07:29:10.299042) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a529eac420652023c75b99fcccae05c1ab085a964807eb873a29df1c1333f7589a89d426bed5d2005ce174f4aa79441d64537afff41441e0b18754ebaaa7a3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088c9322ce6e6711dbe7470cf3c2b0db29d15330adc765395ab7e19a21f9d542697f6c68a7c0c7cdb5c205c49c53ea257d8483e5717c84d51b9688a623214c29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6044c5aa3e09d195e128793695ff5a1bf83265ecb4e48f555e1bd65069e12541235ab17a074c079ce02bdb813c93dc664fa58fee76c5f9ca9f8d801e4225e35f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ea09507b90130251bd26109766002c70628365006c66b5c14cd581bcf1587c41fd667732fd3743f97eda373adbac3c339c17471e82fd6b532ee5c2d3a453d2a"} build_url:https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/eBanjAKoTzSO6ZvRvgOtZg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a529eac420652023c75b99fcccae05c1ab085a964807eb873a29df1c1333f7589a89d426bed5d2005ce174f4aa79441d64537afff41441e0b18754ebaaa7a3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088c9322ce6e6711dbe7470cf3c2b0db29d15330adc765395ab7e19a21f9d542697f6c68a7c0c7cdb5c205c49c53ea257d8483e5717c84d51b9688a623214c29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6044c5aa3e09d195e128793695ff5a1bf83265ecb4e48f555e1bd65069e12541235ab17a074c079ce02bdb813c93dc664fa58fee76c5f9ca9f8d801e4225e35f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ea09507b90130251bd26109766002c70628365006c66b5c14cd581bcf1587c41fd667732fd3743f97eda373adbac3c339c17471e82fd6b532ee5c2d3a453d2a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 07:29:10.324707) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:29:10.325081) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1493993303.77934-1778310189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:29:10.407761) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 07:29:10.408113) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 07:29:10.408414) ========= ========= Total master_lag: 0.16 =========