builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1490062450.53 results: success (0) revision: 5fe5dcf1c10a4523ba3f0a20295551462c2dae11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.527986) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.528468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.528740) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.543184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.543445) ========= 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=db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-20 19:14:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.0M=0s 2017-03-20 19:14:10 (34.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124600 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.683493) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.684046) ========= 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=db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020557 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:10.734777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 19:14:10.735119) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5fe5dcf1c10a4523ba3f0a20295551462c2dae11 --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 5fe5dcf1c10a4523ba3f0a20295551462c2dae11 --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=db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-20 19:14:10,858 truncating revision to first 12 chars 2017-03-20 19:14:10,858 Setting DEBUG logging. 2017-03-20 19:14:10,858 attempt 1/10 2017-03-20 19:14:10,858 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5fe5dcf1c10a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 19:14:12,042 unpacking tar archive at: mozilla-central-5fe5dcf1c10a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.455494 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 19:14:12.203529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:12.203842) ========= script_repo_revision: 5fe5dcf1c10a4523ba3f0a20295551462c2dae11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:12.204246) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:12.204506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 19:14:12.214861) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-20 19:14:12.215219) ========= /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=db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:14:12 INFO - MultiFileLogger online at 20170320 19:14:12 in /builds/slave/test 19:14:12 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 19:14:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:14:12 INFO - {'append_to_log': False, 19:14:12 INFO - 'base_work_dir': '/builds/slave/test', 19:14:12 INFO - 'blob_upload_branch': 'Firefox', 19:14:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:14:12 INFO - 'branch': 'Firefox', 19:14:12 INFO - 'buildbot_json_path': 'buildprops.json', 19:14:12 INFO - 'config_files': ('talos/linux_config.py',), 19:14:12 INFO - 'default_actions': ('clobber', 19:14:12 INFO - 'read-buildbot-config', 19:14:12 INFO - 'download-and-extract', 19:14:12 INFO - 'populate-webroot', 19:14:12 INFO - 'create-virtualenv', 19:14:12 INFO - 'install', 19:14:12 INFO - 'run-tests'), 19:14:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:14:12 INFO - 'download_minidump_stackwalk': True, 19:14:12 INFO - 'download_symbols': 'ondemand', 19:14:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:14:12 INFO - 'tooltool.py': '/tools/tooltool.py', 19:14:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:14:12 INFO - '/tools/misc-python/virtualenv.py')}, 19:14:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:14:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:14:12 INFO - 'gecko_profile': False, 19:14:12 INFO - 'gecko_profile_interval': 0, 19:14:12 INFO - 'installer_path': 'installer.exe', 19:14:12 INFO - 'log_level': 'info', 19:14:12 INFO - 'log_name': 'talos', 19:14:12 INFO - 'log_to_console': True, 19:14:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:14:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:14:12 INFO - 'opt_config_files': (), 19:14:12 INFO - 'pip_index': False, 19:14:12 INFO - 'suite': 'dromaeojs', 19:14:12 INFO - 'system_bits': '32', 19:14:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:14:12 INFO - 'title': 'talos-linux64-ix-031', 19:14:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:14:12 INFO - 'use_talos_json': True, 19:14:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:14:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:14:12 INFO - 'work_dir': 'build'} 19:14:12 INFO - [mozharness: 2017-03-21 02:14:12.435898Z] Running clobber step. 19:14:12 INFO - Running pre-action listener: _resource_record_pre_action 19:14:12 INFO - Running main action method: clobber 19:14:12 INFO - rmtree: /builds/slave/test/build 19:14:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:14:13 INFO - Running post-action listener: _resource_record_post_action 19:14:13 INFO - [mozharness: 2017-03-21 02:14:13.884279Z] Finished clobber step (success) 19:14:13 INFO - [mozharness: 2017-03-21 02:14:13.884377Z] Running read-buildbot-config step. 19:14:13 INFO - Running pre-action listener: _resource_record_pre_action 19:14:13 INFO - Running main action method: read_buildbot_config 19:14:13 INFO - Using buildbot properties: 19:14:13 INFO - { 19:14:13 INFO - "project": "", 19:14:13 INFO - "product": "firefox", 19:14:13 INFO - "who": "kwierso@gmail.com", 19:14:13 INFO - "installer_path": "public/build/target.tar.bz2", 19:14:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:14:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:14:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:14:13 INFO - "stage_platform": "linux64-pgo", 19:14:13 INFO - "basedir": "/builds/slave/test", 19:14:13 INFO - "buildnumber": 85, 19:14:13 INFO - "platform": "ubuntu64_hw", 19:14:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:14:13 INFO - "slavebuilddir": "test", 19:14:13 INFO - "taskId": "SJllkO8fQMiNnWP0hx3YJA", 19:14:13 INFO - "branch": "mozilla-central", 19:14:13 INFO - "script_repo_revision": "production", 19:14:13 INFO - "revision": "5fe5dcf1c10a4523ba3f0a20295551462c2dae11", 19:14:13 INFO - "slavename": "talos-linux64-ix-031", 19:14:13 INFO - "repo_path": "mozilla-central" 19:14:13 INFO - } 19:14:13 INFO - Finding installer, test and symbols from parent task. 19:14:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJllkO8fQMiNnWP0hx3YJA'}, attempt #1 19:14:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJllkO8fQMiNnWP0hx3YJA'}, attempt #1 19:14:14 INFO - Task dependencies: VwmKKWxZQ0KgByCf9vLQ-g 19:14:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g'}, attempt #1 19:14:15 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2 19:14:15 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/test_packages.json 19:14:15 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.crashreporter-symbols.zip 19:14:15 INFO - Running post-action listener: _resource_record_post_action 19:14:15 INFO - [mozharness: 2017-03-21 02:14:15.262385Z] Finished read-buildbot-config step (success) 19:14:15 INFO - [mozharness: 2017-03-21 02:14:15.262601Z] Running download-and-extract step. 19:14:15 INFO - Running pre-action listener: _resource_record_pre_action 19:14:15 INFO - Running main action method: download_and_extract 19:14:15 INFO - mkdir: /builds/slave/test/build/tests 19:14:15 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')]} 19:14:15 INFO - trying https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.test_packages.json 19:14:15 INFO - Downloading https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:14:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:14:15 INFO - Downloaded 986 bytes. 19:14:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:14:15 INFO - Using the following test package requirements: 19:14:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:14:15 INFO - u'common': [u'target.common.tests.zip'], 19:14:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:14:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:14:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:14:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:14:15 INFO - u'mozbase': [u'target.common.tests.zip'], 19:14:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:14:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:14:15 INFO - u'web-platform': [u'target.common.tests.zip', 19:14:15 INFO - u'target.web-platform.tests.zip'], 19:14:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:14:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:14:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.common.tests.zip 19:14:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:14:15 INFO - Fetch https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.common.tests.zip into memory 19:14:16 INFO - Expected file size: 60321646 19:14:16 INFO - Obtained file size: 60321646 19:14:26 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:14:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.common.tests.zip 19:14:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:14:26 INFO - Fetch https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.common.tests.zip into memory 19:14:28 INFO - Expected file size: 60321646 19:14:28 INFO - Obtained file size: 60321646 19:14:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.talos.tests.zip 19:14:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:14:38 INFO - Fetch https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.talos.tests.zip into memory 19:14:39 INFO - Expected file size: 14404171 19:14:39 INFO - Obtained file size: 14404171 19:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:39 INFO - trying https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2 19:14:39 INFO - Downloading https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:14:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:14:40 INFO - Downloaded 67276998 bytes. 19:14:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2 19:14:40 INFO - mkdir: /builds/slave/test/properties 19:14:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:14:40 INFO - Writing to file /builds/slave/test/properties/build_url 19:14:40 INFO - Contents: 19:14:40 INFO - build_url:https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2 19:14:40 INFO - Running post-action listener: _resource_record_post_action 19:14:40 INFO - Running post-action listener: set_extra_try_arguments 19:14:40 INFO - [mozharness: 2017-03-21 02:14:40.881080Z] Finished download-and-extract step (success) 19:14:40 INFO - [mozharness: 2017-03-21 02:14:40.881177Z] Running populate-webroot step. 19:14:40 INFO - Running pre-action listener: _resource_record_pre_action 19:14:40 INFO - Running main action method: populate_webroot 19:14:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:14:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:14:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:14:40 INFO - '--tppagecycles', 19:14:40 INFO - '7'], 19:14:40 INFO - 'tests': ['tsvgm']}}, 19:14:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:14:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:14:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:14:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:14:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['damp', 'tps']}, 19:14:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'tests': ['damp', 'tps']}, 19:14:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['dromaeo_dom']}, 19:14:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:14:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['basic_compositor_video']}, 19:14:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:14:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['a11yr', 19:14:40 INFO - 'ts_paint', 19:14:40 INFO - 'tpaint', 19:14:40 INFO - 'sessionrestore', 19:14:40 INFO - 'sessionrestore_no_auto_restore', 19:14:40 INFO - 'tabpaint']}, 19:14:40 INFO - 'other-e10s': {'tests': ['a11yr', 19:14:40 INFO - 'ts_paint', 19:14:40 INFO - 'tpaint', 19:14:40 INFO - 'sessionrestore', 19:14:40 INFO - 'sessionrestore_no_auto_restore', 19:14:40 INFO - 'tabpaint']}, 19:14:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:14:40 INFO - 'ts_paint', 19:14:40 INFO - 'tpaint', 19:14:40 INFO - 'sessionrestore', 19:14:40 INFO - 'sessionrestore_no_auto_restore', 19:14:40 INFO - 'tabpaint']}, 19:14:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:14:40 INFO - 'ts_paint', 19:14:40 INFO - 'tpaint', 19:14:40 INFO - 'sessionrestore', 19:14:40 INFO - 'sessionrestore_no_auto_restore', 19:14:40 INFO - 'tabpaint']}, 19:14:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['a11yr', 19:14:40 INFO - 'ts_paint', 19:14:40 INFO - 'tpaint', 19:14:40 INFO - 'sessionrestore', 19:14:40 INFO - 'sessionrestore_no_auto_restore', 19:14:40 INFO - 'tabpaint']}, 19:14:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['a11yr', 19:14:40 INFO - 'ts_paint', 19:14:40 INFO - 'tpaint', 19:14:40 INFO - 'sessionrestore', 19:14:40 INFO - 'sessionrestore_no_auto_restore', 19:14:40 INFO - 'tabpaint']}, 19:14:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['tsvgx', 19:14:40 INFO - 'tsvgr_opacity', 19:14:40 INFO - 'tart', 19:14:40 INFO - 'tscrollx', 19:14:40 INFO - 'cart', 19:14:40 INFO - 'tsvg_static']}, 19:14:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:14:40 INFO - 'tsvgr_opacity', 19:14:40 INFO - 'tart', 19:14:40 INFO - 'tscrollx', 19:14:40 INFO - 'cart', 19:14:40 INFO - 'tsvg_static']}, 19:14:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'talos_options': ['--disable-e10s'], 19:14:40 INFO - 'tests': ['tp5o']}, 19:14:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:14:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'talos_options': ['--disable-e10s', 19:14:40 INFO - '--xperf_path', 19:14:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:40 INFO - 'tests': ['tp5n']}, 19:14:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:14:40 INFO - 'talos_options': ['--xperf_path', 19:14:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:40 INFO - 'tests': ['tp5n']}}, 19:14:40 INFO - 'talos.zip': {'path': '', 19:14:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:14:40 INFO - Running post-action listener: _resource_record_post_action 19:14:40 INFO - [mozharness: 2017-03-21 02:14:40.894311Z] Finished populate-webroot step (success) 19:14:40 INFO - [mozharness: 2017-03-21 02:14:40.894401Z] Running create-virtualenv step. 19:14:40 INFO - Running pre-action listener: _resource_record_pre_action 19:14:40 INFO - Running main action method: create_virtualenv 19:14:40 INFO - Creating virtualenv /builds/slave/test/build/venv 19:14:40 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 19:14:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:14:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:14:40 INFO - Using real prefix '/usr' 19:14:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:14:41 INFO - Installing distribute.............................................................................................................................................................................................done. 19:14:43 INFO - Installing pip.................done. 19:14:43 INFO - Return code: 0 19:14:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:14:43 INFO - Reading from file tmpfile_stdout 19:14:43 INFO - Output received: 19:14:43 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) 19:14:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:43 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:43 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 19:14:43 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 19:14:43 INFO - Using env: {'DISPLAY': ':0', 19:14:43 INFO - 'HOME': '/home/cltbld', 19:14:43 INFO - 'LANG': 'en_US.UTF-8', 19:14:43 INFO - 'LANGUAGE': 'en_US:en', 19:14:43 INFO - 'LOGNAME': 'cltbld', 19:14:43 INFO - 'MAIL': '/var/mail/cltbld', 19:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:43 INFO - 'MOZ_NO_REMOTE': '1', 19:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:43 INFO - 'NO_EM_RESTART': '1', 19:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:43 INFO - 'PWD': '/builds/slave/test', 19:14:43 INFO - 'SHELL': '/bin/bash', 19:14:43 INFO - 'SHLVL': '1', 19:14:43 INFO - 'TERM': 'linux', 19:14:43 INFO - 'TMOUT': '86400', 19:14:43 INFO - 'USER': 'cltbld', 19:14:43 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:43 INFO - '_': '/tools/buildbot/bin/python'} 19:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:14:43 INFO - Cleaning up... 19:14:43 INFO - Return code: 0 19:14:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:43 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:43 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 19:14:43 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 19:14:43 INFO - Using env: {'DISPLAY': ':0', 19:14:43 INFO - 'HOME': '/home/cltbld', 19:14:43 INFO - 'LANG': 'en_US.UTF-8', 19:14:43 INFO - 'LANGUAGE': 'en_US:en', 19:14:43 INFO - 'LOGNAME': 'cltbld', 19:14:43 INFO - 'MAIL': '/var/mail/cltbld', 19:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:43 INFO - 'MOZ_NO_REMOTE': '1', 19:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:43 INFO - 'NO_EM_RESTART': '1', 19:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:43 INFO - 'PWD': '/builds/slave/test', 19:14:43 INFO - 'SHELL': '/bin/bash', 19:14:43 INFO - 'SHLVL': '1', 19:14:43 INFO - 'TERM': 'linux', 19:14:43 INFO - 'TMOUT': '86400', 19:14:43 INFO - 'USER': 'cltbld', 19:14:43 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:43 INFO - '_': '/tools/buildbot/bin/python'} 19:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:44 INFO - Downloading/unpacking psutil>=3.1.1 19:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:14:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:46 INFO - Installing collected packages: psutil 19:14:46 INFO - Running setup.py install for psutil 19:14:46 INFO - building 'psutil._psutil_linux' extension 19:14:46 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 19:14:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:14:46 INFO - building 'psutil._psutil_posix' extension 19:14:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:14:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:14:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:46 INFO - Successfully installed psutil 19:14:46 INFO - Cleaning up... 19:14:46 INFO - Return code: 0 19:14:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:46 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:46 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 19:14:46 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 19:14:46 INFO - Using env: {'DISPLAY': ':0', 19:14:46 INFO - 'HOME': '/home/cltbld', 19:14:46 INFO - 'LANG': 'en_US.UTF-8', 19:14:46 INFO - 'LANGUAGE': 'en_US:en', 19:14:46 INFO - 'LOGNAME': 'cltbld', 19:14:46 INFO - 'MAIL': '/var/mail/cltbld', 19:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:46 INFO - 'MOZ_NO_REMOTE': '1', 19:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:46 INFO - 'NO_EM_RESTART': '1', 19:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:46 INFO - 'PWD': '/builds/slave/test', 19:14:46 INFO - 'SHELL': '/bin/bash', 19:14:46 INFO - 'SHLVL': '1', 19:14:46 INFO - 'TERM': 'linux', 19:14:46 INFO - 'TMOUT': '86400', 19:14:46 INFO - 'USER': 'cltbld', 19:14:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:46 INFO - '_': '/tools/buildbot/bin/python'} 19:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:14:49 INFO - Installing collected packages: mozsystemmonitor 19:14:49 INFO - Running setup.py install for mozsystemmonitor 19:14:49 INFO - Successfully installed mozsystemmonitor 19:14:49 INFO - Cleaning up... 19:14:49 INFO - Return code: 0 19:14:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:49 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:49 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 19:14:49 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 19:14:49 INFO - Using env: {'DISPLAY': ':0', 19:14:49 INFO - 'HOME': '/home/cltbld', 19:14:49 INFO - 'LANG': 'en_US.UTF-8', 19:14:49 INFO - 'LANGUAGE': 'en_US:en', 19:14:49 INFO - 'LOGNAME': 'cltbld', 19:14:49 INFO - 'MAIL': '/var/mail/cltbld', 19:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:49 INFO - 'MOZ_NO_REMOTE': '1', 19:14:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:49 INFO - 'NO_EM_RESTART': '1', 19:14:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:49 INFO - 'PWD': '/builds/slave/test', 19:14:49 INFO - 'SHELL': '/bin/bash', 19:14:49 INFO - 'SHLVL': '1', 19:14:49 INFO - 'TERM': 'linux', 19:14:49 INFO - 'TMOUT': '86400', 19:14:49 INFO - 'USER': 'cltbld', 19:14:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:49 INFO - '_': '/tools/buildbot/bin/python'} 19:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:49 INFO - Downloading/unpacking jsonschema==2.5.1 19:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:14:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:51 INFO - Downloading functools32-3.2.3-2.tar.gz 19:14:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:14:51 INFO - warning: no files found matching '*.txt' 19:14:51 INFO - no previously-included directories found matching 'build' 19:14:51 INFO - no previously-included directories found matching 'dist' 19:14:51 INFO - no previously-included directories found matching '.git*' 19:14:51 INFO - Installing collected packages: jsonschema, functools32 19:14:51 INFO - Running setup.py install for functools32 19:14:51 INFO - warning: no files found matching '*.txt' 19:14:51 INFO - no previously-included directories found matching 'build' 19:14:51 INFO - no previously-included directories found matching 'dist' 19:14:51 INFO - no previously-included directories found matching '.git*' 19:14:51 INFO - Successfully installed jsonschema functools32 19:14:51 INFO - Cleaning up... 19:14:51 INFO - Return code: 0 19:14:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:51 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:51 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 19:14:51 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 19:14:51 INFO - Using env: {'DISPLAY': ':0', 19:14:51 INFO - 'HOME': '/home/cltbld', 19:14:51 INFO - 'LANG': 'en_US.UTF-8', 19:14:51 INFO - 'LANGUAGE': 'en_US:en', 19:14:51 INFO - 'LOGNAME': 'cltbld', 19:14:51 INFO - 'MAIL': '/var/mail/cltbld', 19:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:51 INFO - 'MOZ_NO_REMOTE': '1', 19:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:51 INFO - 'NO_EM_RESTART': '1', 19:14:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:51 INFO - 'PWD': '/builds/slave/test', 19:14:51 INFO - 'SHELL': '/bin/bash', 19:14:51 INFO - 'SHLVL': '1', 19:14:51 INFO - 'TERM': 'linux', 19:14:51 INFO - 'TMOUT': '86400', 19:14:51 INFO - 'USER': 'cltbld', 19:14:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:51 INFO - '_': '/tools/buildbot/bin/python'} 19:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:14:52 INFO - Cleaning up... 19:14:52 INFO - Return code: 0 19:14:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:52 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:52 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 19:14:52 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 19:14:52 INFO - Using env: {'DISPLAY': ':0', 19:14:52 INFO - 'HOME': '/home/cltbld', 19:14:52 INFO - 'LANG': 'en_US.UTF-8', 19:14:52 INFO - 'LANGUAGE': 'en_US:en', 19:14:52 INFO - 'LOGNAME': 'cltbld', 19:14:52 INFO - 'MAIL': '/var/mail/cltbld', 19:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:52 INFO - 'MOZ_NO_REMOTE': '1', 19:14:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:52 INFO - 'NO_EM_RESTART': '1', 19:14:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:52 INFO - 'PWD': '/builds/slave/test', 19:14:52 INFO - 'SHELL': '/bin/bash', 19:14:52 INFO - 'SHLVL': '1', 19:14:52 INFO - 'TERM': 'linux', 19:14:52 INFO - 'TMOUT': '86400', 19:14:52 INFO - 'USER': 'cltbld', 19:14:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:52 INFO - '_': '/tools/buildbot/bin/python'} 19:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:52 INFO - Downloading/unpacking blobuploader==1.2.4 19:14:52 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 19:14:52 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 19:14:52 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 19:14:52 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 19:14:52 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 19:14:52 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 19:14:54 INFO - Downloading blobuploader-1.2.4.tar.gz 19:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:14:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:14:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:55 INFO - Downloading docopt-0.6.1.tar.gz 19:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:14:55 INFO - Installing collected packages: blobuploader, requests, docopt 19:14:55 INFO - Running setup.py install for blobuploader 19:14:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:14:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:14:55 INFO - Running setup.py install for requests 19:14:55 INFO - Running setup.py install for docopt 19:14:55 INFO - Successfully installed blobuploader requests docopt 19:14:55 INFO - Cleaning up... 19:14:55 INFO - Return code: 0 19:14:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:55 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:55 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 19:14:55 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 19:14:55 INFO - Using env: {'DISPLAY': ':0', 19:14:55 INFO - 'HOME': '/home/cltbld', 19:14:55 INFO - 'LANG': 'en_US.UTF-8', 19:14:55 INFO - 'LANGUAGE': 'en_US:en', 19:14:55 INFO - 'LOGNAME': 'cltbld', 19:14:55 INFO - 'MAIL': '/var/mail/cltbld', 19:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:55 INFO - 'MOZ_NO_REMOTE': '1', 19:14:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:55 INFO - 'NO_EM_RESTART': '1', 19:14:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:55 INFO - 'PWD': '/builds/slave/test', 19:14:55 INFO - 'SHELL': '/bin/bash', 19:14:55 INFO - 'SHLVL': '1', 19:14:55 INFO - 'TERM': 'linux', 19:14:55 INFO - 'TMOUT': '86400', 19:14:55 INFO - 'USER': 'cltbld', 19:14:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:55 INFO - '_': '/tools/buildbot/bin/python'} 19:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:56 INFO - Running setup.py (path:/tmp/pip-nMvgve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:56 INFO - Running setup.py (path:/tmp/pip-3pPVM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:56 INFO - Running setup.py (path:/tmp/pip-U_kZ4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:56 INFO - Running setup.py (path:/tmp/pip-lwuEA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:56 INFO - Running setup.py (path:/tmp/pip-ALsrGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:56 INFO - Running setup.py (path:/tmp/pip-t4td9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:56 INFO - Running setup.py (path:/tmp/pip-96rzjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:56 INFO - Running setup.py (path:/tmp/pip-q4mFg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:56 INFO - Running setup.py (path:/tmp/pip-O4FIF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:56 INFO - Running setup.py (path:/tmp/pip-UTxqW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:56 INFO - Running setup.py (path:/tmp/pip-hQHCPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:56 INFO - Running setup.py (path:/tmp/pip-xps81j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:56 INFO - Running setup.py (path:/tmp/pip-YCTr_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:56 INFO - Running setup.py (path:/tmp/pip-RHsYVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:57 INFO - Running setup.py (path:/tmp/pip-k_L3AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:57 INFO - Running setup.py (path:/tmp/pip-qzzQdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:57 INFO - Running setup.py (path:/tmp/pip-0U6hh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:14:57 INFO - Running setup.py install for manifestparser 19:14:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:14:57 INFO - Running setup.py install for mozcrash 19:14:57 INFO - Running setup.py install for mozdebug 19:14:57 INFO - Running setup.py install for mozdevice 19:14:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:14:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:14:57 INFO - Running setup.py install for mozfile 19:14:57 INFO - Running setup.py install for mozhttpd 19:14:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:14:57 INFO - Running setup.py install for mozinfo 19:14:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:14:57 INFO - Running setup.py install for mozInstall 19:14:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Running setup.py install for mozleak 19:14:58 INFO - Running setup.py install for mozlog 19:14:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Running setup.py install for moznetwork 19:14:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Running setup.py install for mozprocess 19:14:58 INFO - Running setup.py install for mozprofile 19:14:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Running setup.py install for mozrunner 19:14:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:14:58 INFO - Running setup.py install for mozscreenshot 19:14:58 INFO - Running setup.py install for moztest 19:14:58 INFO - Running setup.py install for mozversion 19:14:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:14:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:14:59 INFO - Cleaning up... 19:14:59 INFO - Return code: 0 19:14:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:59 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:14:59 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 19:14:59 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 19:14:59 INFO - Using env: {'DISPLAY': ':0', 19:14:59 INFO - 'HOME': '/home/cltbld', 19:14:59 INFO - 'LANG': 'en_US.UTF-8', 19:14:59 INFO - 'LANGUAGE': 'en_US:en', 19:14:59 INFO - 'LOGNAME': 'cltbld', 19:14:59 INFO - 'MAIL': '/var/mail/cltbld', 19:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:59 INFO - 'MOZ_NO_REMOTE': '1', 19:14:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:59 INFO - 'NO_EM_RESTART': '1', 19:14:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:59 INFO - 'PWD': '/builds/slave/test', 19:14:59 INFO - 'SHELL': '/bin/bash', 19:14:59 INFO - 'SHLVL': '1', 19:14:59 INFO - 'TERM': 'linux', 19:14:59 INFO - 'TMOUT': '86400', 19:14:59 INFO - 'USER': 'cltbld', 19:14:59 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:59 INFO - '_': '/tools/buildbot/bin/python'} 19:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:59 INFO - Running setup.py (path:/tmp/pip-KTMjnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:59 INFO - Running setup.py (path:/tmp/pip-PIphXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:59 INFO - Running setup.py (path:/tmp/pip-pmBgBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:59 INFO - Running setup.py (path:/tmp/pip-hziffu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:59 INFO - Running setup.py (path:/tmp/pip-H9N56E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:59 INFO - Running setup.py (path:/tmp/pip-kVV1eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:59 INFO - Running setup.py (path:/tmp/pip-mNNII1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:59 INFO - Running setup.py (path:/tmp/pip-Ylh10E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:59 INFO - Running setup.py (path:/tmp/pip-FP2FnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:59 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)) 19:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:59 INFO - Running setup.py (path:/tmp/pip-Dwbwiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:00 INFO - Running setup.py (path:/tmp/pip-Y7dkdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:00 INFO - Running setup.py (path:/tmp/pip-MTvCsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:00 INFO - Running setup.py (path:/tmp/pip-iF0MB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:00 INFO - Running setup.py (path:/tmp/pip-aoh28_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:00 INFO - Running setup.py (path:/tmp/pip-0BrFnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:00 INFO - Running setup.py (path:/tmp/pip-aIN_8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:00 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)) 19:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:00 INFO - Running setup.py (path:/tmp/pip-RejbTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:00 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)) 19:15:00 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)) 19:15:00 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)) 19:15:00 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)) 19:15:00 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:15:00 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:15:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:15: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 19:15: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 19:15: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 19:15: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 19:15: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 19:15: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 19:15:02 INFO - Downloading blessings-1.6.tar.gz 19:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:15:02 INFO - Installing collected packages: blessings 19:15:02 INFO - Running setup.py install for blessings 19:15:02 INFO - Successfully installed blessings 19:15:02 INFO - Cleaning up... 19:15:02 INFO - Return code: 0 19:15:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:02 INFO - Reading from file tmpfile_stdout 19:15:02 INFO - Current package versions: 19:15:02 INFO - argparse == 1.2.1 19:15:02 INFO - blessings == 1.6 19:15:02 INFO - blobuploader == 1.2.4 19:15:02 INFO - docopt == 0.6.1 19:15:02 INFO - functools32 == 3.2.3-2 19:15:02 INFO - jsonschema == 2.5.1 19:15:02 INFO - manifestparser == 1.1 19:15:02 INFO - mozInstall == 1.12 19:15:02 INFO - mozcrash == 1.0 19:15:02 INFO - mozdebug == 0.1 19:15:02 INFO - mozdevice == 0.49 19:15:02 INFO - mozfile == 1.2 19:15:02 INFO - mozhttpd == 0.7 19:15:02 INFO - mozinfo == 0.9 19:15:02 INFO - mozleak == 0.1 19:15:02 INFO - mozlog == 3.4 19:15:02 INFO - moznetwork == 0.27 19:15:02 INFO - mozprocess == 0.25 19:15:02 INFO - mozprofile == 0.28 19:15:02 INFO - mozrunner == 6.13 19:15:02 INFO - mozscreenshot == 0.1 19:15:02 INFO - mozsystemmonitor == 0.3 19:15:02 INFO - moztest == 0.8 19:15:02 INFO - mozversion == 1.4 19:15:02 INFO - psutil == 3.1.1 19:15:02 INFO - requests == 1.2.3 19:15:02 INFO - wsgiref == 0.1.2 19:15:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:03 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:15:03 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 19:15:03 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 19:15:03 INFO - Using env: {'DISPLAY': ':0', 19:15:03 INFO - 'HOME': '/home/cltbld', 19:15:03 INFO - 'LANG': 'en_US.UTF-8', 19:15:03 INFO - 'LANGUAGE': 'en_US:en', 19:15:03 INFO - 'LOGNAME': 'cltbld', 19:15:03 INFO - 'MAIL': '/var/mail/cltbld', 19:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:03 INFO - 'MOZ_NO_REMOTE': '1', 19:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:03 INFO - 'NO_EM_RESTART': '1', 19:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:03 INFO - 'PWD': '/builds/slave/test', 19:15:03 INFO - 'SHELL': '/bin/bash', 19:15:03 INFO - 'SHLVL': '1', 19:15:03 INFO - 'TERM': 'linux', 19:15:03 INFO - 'TMOUT': '86400', 19:15:03 INFO - 'USER': 'cltbld', 19:15:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:03 INFO - '_': '/tools/buildbot/bin/python'} 19:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 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)) 19:15:03 INFO - Cleaning up... 19:15:03 INFO - Return code: 0 19:15:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:15:03 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')]} 19:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:03 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')]} 19:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:03 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 0x29781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c16310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, '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': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', '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 19:15:03 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 19:15:03 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 19:15:03 INFO - Using env: {'DISPLAY': ':0', 19:15:03 INFO - 'HOME': '/home/cltbld', 19:15:03 INFO - 'LANG': 'en_US.UTF-8', 19:15:03 INFO - 'LANGUAGE': 'en_US:en', 19:15:03 INFO - 'LOGNAME': 'cltbld', 19:15:03 INFO - 'MAIL': '/var/mail/cltbld', 19:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:03 INFO - 'MOZ_NO_REMOTE': '1', 19:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:03 INFO - 'NO_EM_RESTART': '1', 19:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:03 INFO - 'PWD': '/builds/slave/test', 19:15:03 INFO - 'SHELL': '/bin/bash', 19:15:03 INFO - 'SHLVL': '1', 19:15:03 INFO - 'TERM': 'linux', 19:15:03 INFO - 'TMOUT': '86400', 19:15:03 INFO - 'USER': 'cltbld', 19:15:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:03 INFO - '_': '/tools/buildbot/bin/python'} 19:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:15:03 INFO - Cleaning up... 19:15:03 INFO - Return code: 0 19:15:03 INFO - Running post-action listener: _resource_record_post_action 19:15:03 INFO - Running post-action listener: _start_resource_monitoring 19:15:03 INFO - Starting resource monitoring. 19:15:03 INFO - [mozharness: 2017-03-21 02:15:03.492313Z] Finished create-virtualenv step (success) 19:15:03 INFO - [mozharness: 2017-03-21 02:15:03.492834Z] Running install step. 19:15:03 INFO - Running pre-action listener: _resource_record_pre_action 19:15:03 INFO - Running main action method: install 19:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:03 INFO - Reading from file tmpfile_stdout 19:15:03 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:03 INFO - Reading from file tmpfile_stdout 19:15:03 INFO - Output received: 19:15:03 INFO - Usage: mozinstall [options] installer 19:15:03 INFO - Options: 19:15:03 INFO - -h, --help show this help message and exit 19:15:03 INFO - -d DEST, --destination=DEST 19:15:03 INFO - Directory to install application into. [default: 19:15:03 INFO - "/builds/slave/test"] 19:15:03 INFO - --app=APP Application being installed. [default: firefox] 19:15:03 INFO - mkdir: /builds/slave/test/build/application 19:15:03 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'] 19:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:15:20 INFO - Reading from file tmpfile_stdout 19:15:20 INFO - Output received: 19:15:20 INFO - /builds/slave/test/build/application/firefox/firefox 19:15:20 INFO - Running post-action listener: _resource_record_post_action 19:15:20 INFO - [mozharness: 2017-03-21 02:15:20.752791Z] Finished install step (success) 19:15:20 INFO - [mozharness: 2017-03-21 02:15:20.753067Z] Running run-tests step. 19:15:20 INFO - Running pre-action listener: _resource_record_pre_action 19:15:20 INFO - Running main action method: run_tests 19:15:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:15:20 INFO - Python 2.7.3 19:15:20 INFO - Return code: 0 19:15:20 INFO - grabbing minidump binary from tooltool 19:15:20 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')]} 19:15:20 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 0x2c13400>, '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 0x2c143b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c14840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:15:20 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 19:15:20 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 19:15:20 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 19:15:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:15:20 INFO - Return code: 0 19:15:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:15:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:15:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:15:20 INFO - ENV: RUST_BACKTRACE is now 1 19:15:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:15:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:15:20 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/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 19:15:20 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/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 19:15:20 INFO - Using env: {'DISPLAY': ':0', 19:15:20 INFO - 'HOME': '/home/cltbld', 19:15:20 INFO - 'LANG': 'en_US.UTF-8', 19:15:20 INFO - 'LANGUAGE': 'en_US:en', 19:15:20 INFO - 'LOGNAME': 'cltbld', 19:15:20 INFO - 'MAIL': '/var/mail/cltbld', 19:15:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:15:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:20 INFO - 'MOZ_NO_REMOTE': '1', 19:15:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:15:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:20 INFO - 'NO_EM_RESTART': '1', 19:15:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:20 INFO - 'PWD': '/builds/slave/test', 19:15:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:15:20 INFO - 'RUST_BACKTRACE': '1', 19:15:20 INFO - 'SHELL': '/bin/bash', 19:15:20 INFO - 'SHLVL': '1', 19:15:20 INFO - 'TERM': 'linux', 19:15:20 INFO - 'TMOUT': '86400', 19:15:20 INFO - 'USER': 'cltbld', 19:15:20 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080', 19:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:20 INFO - '_': '/tools/buildbot/bin/python'} 19:15:20 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/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 19:15:21 INFO - mozversion application_buildid: 20170321012002 19:15:21 INFO - mozversion application_changeset: 5fe5dcf1c10a4523ba3f0a20295551462c2dae11 19:15:21 INFO - mozversion application_display_name: Nightly 19:15:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:15:21 INFO - mozversion application_name: Firefox 19:15:21 INFO - mozversion application_remotingname: firefox 19:15:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:15:21 INFO - mozversion application_vendor: Mozilla 19:15:21 INFO - mozversion application_version: 55.0a1 19:15:21 INFO - mozversion platform_buildid: 20170321012002 19:15:21 INFO - mozversion platform_changeset: 5fe5dcf1c10a4523ba3f0a20295551462c2dae11 19:15:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:15:21 INFO - mozversion platform_version: 55.0a1 19:15:21 INFO - using testdate: 1490062521 19:15:21 INFO - actual date: 1490062521 19:15:21 INFO - starting webserver on 'localhost:51900' 19:15:21 INFO - SUITE-START | Running 2 tests 19:15:21 INFO - TEST-START | dromaeo_css 19:15:21 INFO - operating with platform_type : linux_ 19:15:21 INFO - Initialising browser for dromaeo_css test... 19:15:21 INFO - TEST-INFO | started process 4995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzoonQK/profile http://localhost:51900/getInfo.html) 19:15:25 INFO - PID 4995 | __metrics Screen width/height:1600/1200 19:15:25 INFO - PID 4995 | colorDepth:24 19:15:25 INFO - PID 4995 | Browser inner width/height: 1024/697 19:15:25 INFO - PID 4995 | __metrics 19:15:26 INFO - PID 4995 | 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" 19:15:26 INFO - PID 4995 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:27 INFO - TEST-INFO | 4995: exit 0 19:15:27 INFO - Browser initialized. 19:15:27 INFO - Running cycle 1/1 for dromaeo_css test... 19:15:27 INFO - TEST-INFO | started process 5088 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzoonQK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:38 INFO - PID 5088 | RSS: Main: 144703488 19:15:38 INFO - PID 5088 | 19:18:41 INFO - PID 5088 | Cycle 1(1): loaded http://localhost:51900/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51900/tests/dromaeo/cssquery-ext.html) 19:18:43 INFO - PID 5088 | RSS: Main: 297930752 19:18:43 INFO - PID 5088 | 19:20:27 INFO - PID 5088 | ************************* 19:20:27 INFO - PID 5088 | A coding exception was thrown and uncaught in a Task. 19:20:27 INFO - PID 5088 | 19:20:27 ERROR - PID 5088 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:20:27 INFO - PID 5088 | Full stack: 19:20:27 INFO - PID 5088 | ************************* 19:21:45 INFO - PID 5088 | Cycle 1(1): loaded http://localhost:51900/tests/dromaeo/cssquery-ext.html (next: http://localhost:51900/tests/dromaeo/cssquery-jquery.html) 19:21:45 INFO - PID 5088 | RSS: Main: 232112128 19:21:45 INFO - PID 5088 | 19:24:47 INFO - PID 5088 | Cycle 1(1): loaded http://localhost:51900/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51900/tests/dromaeo/cssquery-mootools.html) 19:24:48 INFO - PID 5088 | RSS: Main: 221589504 19:24:48 INFO - PID 5088 | 19:27:50 INFO - PID 5088 | Cycle 1(1): loaded http://localhost:51900/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51900/tests/dromaeo/cssquery-prototype.html) 19:27:50 INFO - PID 5088 | RSS: Main: 191893504 19:27:50 INFO - PID 5088 | 19:30:53 INFO - PID 5088 | Cycle 1(1): loaded http://localhost:51900/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51900/tests/dromaeo/cssquery-yui.html) 19:30:54 INFO - PID 5088 | RSS: Main: 182607872 19:30:54 INFO - PID 5088 | 19:33:56 INFO - PID 5088 | Cycle 1(1): loaded http://localhost:51900/tests/dromaeo/cssquery-yui.html (next: http://localhost:51900/tests/dromaeo/cssquery-dojo.html) 19:33:57 INFO - PID 5088 | RSS: Main: 269881344 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | __start_tp_report 19:33:57 INFO - PID 5088 | _x_x_mozilla_page_load 19:33:57 INFO - PID 5088 | _x_x_mozilla_page_load_details 19:33:57 INFO - PID 5088 | |i|pagename|runs| 19:33:57 INFO - PID 5088 | |0;dojo.html;3284.277458959029;3314.3205642843204;3335.166208447887;3347.581552305962;3372.6121496027963;5678.716064196784;5715.771369145229;5757.769689212431;5763.049096899012;5795.043817770063;5680.289963754531;5688.004599195135;5709.0580054290995;5773.740181691824;5869.882602347961;5055.949440505603;5110.897782044357;5172.250023746573;5247.239150323201;5250.4487028861995;5221.947780522184;5270.420253772082;5276.868078298035;5281.551068159211;5331.200319952019;5122.975404918994;5144.717040562787;5167.534921857051;5168.638195326329;5200.817971371002;5210.895782084337;5215.139501982168;5223.947760522422;5300.2314664373835;5304.575633949275;5122.385313762373;5190.143626301656;5194.818181363634;5232.816851410183;5241.711705856176;5585.2459917910965;5648.548116150698;5809.04150815115;5816.505597024258;5822.330432000332;6153.876922461525;6159.722812473449;6209.130721698965;6231.906521402183;6239.2824825145235;6328.050792381134;6337.809865704036;6354.364563543655;6354.650494222816;6357.046443033536;5859.267591551056;5860.677662728505;5870.148828419855;5873.970630146865;5877.147813567009;6245.656488893063;6300.968495157586;6325.810225693236;6345.016522438937;6395.6482393468805;6037.275526936708;6087;6088.4215999479975;6097.796915383898;6111.105587097633;6174.567780255339;6259.436650701458;6288.52836037299;6291.591046582049;6292.528060395489;6131.172291740558;6281.8743625127245;6295.779647712399;6302.338254483305;6305.117283580211;6103.428585615297;6122.367660829284;6143.72682107623;6201;6203.069539569011;4780;4780.235162374003;4786.473487916326;4812.759362031885;4816.903661926742;4222.852200173041;4227.196832601796;4230.830766769356;4231.851885184065;4269.188854117708;5849.88300233993;5857.121431785266;5911.852203694942;5957;5972.343042265347;6168.9691551541955;6194.34959329273;6221.751129954841;6225.439710426083;6233.096201050822;5850.8864904136035;5855.473007429352;5857.824265272048;5863.061910094364;5901.675407852609;5206.687598744087;5233.241180028875;5240.633155679142;5291.894162116736;5325.4674532547215;6561.212654481397;6592.76925307612;6595.142631457879;6599.208095028627;6599.571027883172;6305.085762564494;6342.682865856596;6360.49116070704;6364.077208804605;6367.204099487564;6248;6383.457406120346;6558.04908288286;6567;6571.80284591463;6126.975740326223;6127.0062805303205;6149.907751383822;6172.814815555541;6177.598456100339;6245.437910588069;6312.116303717575;6321.1782468278825;6321.810345689637;6329.430351268224;5990.550708696952;5995.700214989321;6035.155078289131;6061.939380606137;6153.907691384543;6098.099428985257;6123.069106197319;6129.859247658277;6143.187759611015;6148;6106.969465152646;6121.056693971993;6131.162586540671;6134.601250918676;6136.32500424944;6042.909356359746;6053.727582258721;6074.271087469532;6141.829680688692;6183.196184495987;5922.111683247375;6005.339412664516;6045.969770151297;6050.485708714808;6052.66710330936 19:33:57 INFO - PID 5088 | |1;ext.html;636.3636363636364;674.7166190200119;679.6601699150425;696.940760035397;710.6730903784261;29797.702022979764;36732.26535469292;37238.813805930935;37296.254074918485;37391.81304093482;38913;39276.60723392765;39322;39337.60662393375;39387.212255754865;2110.757262914769;2155.181031208139;2419.540287345403;2474.925752227436;2489.0541594194183;2595.9351016224555;2673.4252135790803;2675.1573254424893;2675.210812810216;2681.4905168018013;2607.9348016299555;2654.86725663717;2660.401409682825;2667.1065193160316;2670.3190686375046;2642.7753640940446;2662.361033352001;2666.1201803405024;2668.43962767819;2668.8799004044863;2404.5671779079757;2419.4072452249134;2424.963625545618;2435.585950388438;2436.6223235398543;36522.452163217305;37170.256594868224;37178;37182.628173718185;37200.25599488022;2966.1142715805095;2973.717496837797;3012.0662594595747;3018.0644000359957;3028.787984841062;27829.582556261674;27903.907866718626;27939.720602793914;28182.859085704644;28788.424231515462;3631.963680363215;3661.743677942545;3677.0623491009887;3709.9443508347395;3723.94414083789;202159;202395;202400;202570;202983.98508007513;48938.51061489339;49572.25641615379;49581.25628115581;49598;49618.255726164134;51571.742141289054;51679.74160129175;51755;51761.741191293804;51842.74078629583;14130.434782608787;14391.64020899465;14421.278936053155;14458.783118253235;14475.638109047148;10219.284650074573;10356.067953884187;11146.27549209315;11968.940155299168;12124.090693198046;13144.671383215496;13317;13398.665033374049;13443.731125377437;13534.661633459242;5110.540051395392;5114.258432527263;5196.467042222554;5325.760340784674;5350.785968561292;1031.6234574380446;1035.1936020664164;1055.7782865598156;1060.671191930504;1078.245228340165;24785.62821557678;24852.25443236706;25136.49727005486;25170.24489265325;25268.747312527008;1147.5041784119508;1154.4458659843322;1171.4435643069644;1174.4656181437426;1187.8337032815414;12164.695882603006;12831.4602247729;13125.34373281332;13152.342382880817;13205.801912971314;271556;272762;272832;272952;273517;52116.47883521192;52157;52168.95719077952;52173.47826521762;52235.73882130565;1440.1503113163285;1466.516049703574;1482.844301348343;1488.151753500516;1488.3376897280823;23435.531289373776;24599;26101.608475872497;29094.27264318409;29664;48078.27882581694;48114.27828582504;48153.75923120502;48160.75919620379;48233.03533929231;231206;231574;231735;231804;232074;25842;26042.609360859224;26063.869680651474;31528.211794705316;31658;37627.435588466746;37766;37774.24451511009;37784.43323350097;38136.809315953244;33905.83047084749;33907.49138762871;33982;34344;34501;33602.83198583991;33765.83117084399;33966;34106;35071.47392789161;35221.82389088038;35245;35537.64462355343;36004.639953601174;36203.456948146326;29147.417051658424;30658.693413065583;30917.0724878254;30987;31072;24340.878295608407;24382;24514;24874.25377238686;24966.251012469656 19:33:57 INFO - PID 5088 | |2;jquery.html;431.7754767520887;443.35713215837035;452.57005844447764;460.433666590094;464.505301853526;18176.18207180677;19395.418137455883;19855.602887942234;19944.401667949947;19979.500512487197;19031.809681903178;19265.422037338867;19295.324663636777;19375.128119234632;19493.707594386095;4349.565043495649;4508.481524624677;4511.932321015187;4514.932276015862;4517.322401639747;4424.137293227822;4470.97764511177;4483.08096840147;4492.797824097907;4496.64026877849;4316.352547117926;4420.204363214636;4444.911101777962;4457.219986502365;4464.6874718769695;4388.714733542325;4439.5560443955665;4442.577955094261;4444.888877778065;4447.555244475527;3893.8442462301473;4008.5590585035625;4044.4337792709043;4132.359484279941;4134.73124246923;25765.61351579709;26104.216873493635;26119.869400652875;26158;26268.635822776043;1970.7733610634816;1977.5253939054667;1983.2166294313809;1995.6108558831033;2024.8785072895673;16098.597535061717;16197.433089841918;16386.26261818221;16457.341706331732;16811.075390853497;1718.2096235731578;1739.782527184102;1758.6658534878331;1779.1904683369037;1831.5146486181172;95618;98783.06084695736;99049;99188.50405747998;99572;12189.75620487603;12299.877001229874;12410.627681169579;12523.499060037497;12566.246025238515;17579.82420175782;17590.38433654816;17595.120243988007;17648.294068237126;17651.73522397165;8643.611037503328;8887.200151986352;8978.86531702025;9059.184673379295;9107.726768196964;8189.795255118551;8249.628766705513;8263.917360826315;8387.412881098386;8401.201885820838;7751.263629955145;7751.689932402754;7759.301662850257;7811.218878112257;7829.256220659028;2192.397090800043;2238.317313219484;2247.8876056197123;2264.682944387787;2362.9527409452057;965.9903400966041;987.3088837813559;987.8123156600223;1000.6997900629782;1011.4790882695418;19226.80773192278;19498.512537186685;19629.31297404584;19654.901725491567;19724.50688732793;1003.5935446144323;1009.3741880034279;1012.6556970630021;1017.9440130792877;1041.265907535186;10298.845517317246;10630.787384252271;10681.980480837994;10784.352938823695;10814.837777433344;521324;524359;536382;549135;550643;93127;93619.53190234005;93703.53148234352;94268;94474;1209.4013463335705;1234.5864135514703;1257.1514227896134;1265.373640048182;1278.910476266671;18062;19272.22911083541;20282.79717202868;20453.590928181056;20474.488137796674;38624;38758.4186237201;41458.37812432756;47779;48544;62610.373896260455;62908.68545657242;67744.6612766933;69005;69824;11764.764704706029;12224.266544007389;12968.740625187633;13345.399457024263;13877.930610346884;8640.611172497378;13801.723965520834;13803.51687690926;14744.926275368984;14861.702765944405;11882.524699012289;12433.626991190278;12491.625251242478;12546.1844980076;12707.174033687781;12002.939985300367;12079.51681932713;12201.328926909106;12424.75150497003;12703;11543.249688770204;11555.768884622428;11707.473163707484;11885.91996400609;12582.874171258536;13097.67255818574;13183.209007459584;13201.669958251121;13229.140105893086;13364.331783410596;12237.816432753338;12387.19483233587;12414.193077449936;12475.875241247471;13083.869161308647 19:33:57 INFO - PID 5088 | |3;mootools.html;1195.6114262864571;1326.966825829354;1365.160426337802;1379.8206233189687;1390.5550223928335;9209.677661281856;10418.947905260473;10624.734381640443;10657.680269591914;10679.83980240297;10735.946320268387;10791.946040269788;10798.838017429745;10825.512851921663;10830.72923176921;1966.7246585478044;2040.4898775306174;2390.6175011998084;2425.7938075263623;2427.9757202427927;2321.3384185507134;2389.4026493376655;2395.784379405853;2398.8080953523677;2403.8197135214928;2295.357299956015;2328.685627440291;2339.9298021059312;2348.906043758256;2351.43565544269;2302.6200676888293;2330.3125577954543;2330.8718020508863;2335.9532809343887;2339.228054741931;2070.823979961705;2073.6163809695254;2076.2421716073677;2130.7549631792385;2280.1221529711015;10911.127109831194;10957.726056848564;10969.83545246822;10971.890281097167;10973.615923442721;6058.909116363258;6241.65670888101;6253.843653908643;6259.998400255937;6263.185785847811;3605.170810713524;3766.462103029153;3970.2059588082498;3992.1017771001584;4005.61946615074;1304.4782087165115;1346.4344975110478;1373.9725205495834;1419.7089596632666;1429.5997120806087;16376;16436.75344869828;16438.67122657552;16445.58886027847;16458.259378328003;5359.22291267764;5376.569874410038;5541.695206763586;5625.6905870176715;5655.830325090254;13571.59285221445;13656.726865462635;13661;13698.109622874481;13703.931480342535;6425.068365087036;6431.678416079178;6432.807015789534;6438.066480360321;6438.323975982548;4968.726720030433;5122.743862806845;5479.945200548023;5594.664320140804;5633.521150702154;4370.497392799869;4388.144311859157;4398.648108151404;4449.065696203768;4463.821447142078;1388.5556621881103;1413.9080959737585;1417.8298604167567;1428.271581493425;1428.5357258890901;823.625250511016;824.5258976088772;827.3877330775304;836.8200836820113;840.6133178737726;6169.81490555284;6349.650769207646;6463.806085817433;6516.696660667885;6581.901271480931;601.6420229963165;617.1051974636795;619.5724949784634;623.7037407231529;625.998402555916;3795.1460921292487;3822.9929287124482;3827.483289755903;3840.347140985983;3883.650471457582;13968.720625587635;14539.41842326295;14552.49066282675;14618.561443156723;14653.560393188221;14328.283585820667;14462.349194286282;14485.130892146293;14509.85490145106;14524;10206.744831379126;10335.06984371395;10402.063814256753;10563.626728525243;10563.735906602396;6267.96866015667;6298.84252893663;6360.363963603602;6421.197350331208;6465.935340646722;12555;12971.740565188455;13024;13094.803577946148;13172.73654526885;12737;12979.221246725227;12993;13024.153430027018;13231.603051908458;7904.881426778488;8908.643654253758;9129.45223286605;9237.168654821098;9324.347295689508;10098.697039088838;10118.08937195656;10549.156067514426;10552.841707374242;10560.524776384927;8668.263197628272;8765.0358460568;8773.078826723358;8829.205371516595;8991.55042247898;8754.69358572447;8796.91203087961;8809.867851982097;8998.14517620825;9024.59389327495;7799.766007019572;7826.491278066907;7846.725364612211;7896.052473703192;7933.206679332021;6022.939770602238;6215.595986260879;6216.067589861376;6232.25212974446;6288.968555157195;5964.731587078679;5980.282366116094;6070;6145.723442445012;6193.84515387119 19:33:57 INFO - PID 5088 | |4;prototype.html;414.6972709921759;415.1303020172739;430.8492027790273;437.3112348051818;437.5362116156874;5645.774169033239;5718.1994520767075;5741.598088133822;5748.5401167906575;5750.913736293954;5399.649022813524;5592.468715472034;5629.60592758507;5650.971745141269;5667.149927510875;4406.810161256459;4406.955930440703;4407.184670835905;4420.916875365524;4429.202743506167;4431.778411079449;4433.534478879704;4446.644268458532;4447.822087116528;4448.666350023761;4210.863066971916;4379.1022840317655;4390.648748100144;4399.27411977025;4412.183746006983;4376.387305777193;4377.978110109461;4380.6057454829215;4389.144116897206;4393.978030109861;3889.7358358533306;3899.083715326896;3912.3544615138467;3923.117298607819;3924.5879182685694;5378.247045413606;5504.119340905476;5548.41998470713;5578.972105139489;5583.246261754652;5111.131107711698;5114.769835357418;5128.974205158983;5131.923021154686;5139.12634852076;5976.64140151588;5986.8503287417725;5991.221141251653;5995.400459954013;6010.699465026732;4221.831126754926;4264.914701705949;4324.7025892232205;4340.8697739067875;4353.499347575006;5913.615614985012;6002.658963004432;6121.785737499166;6225.346338634468;6284.277308109535;4423.270160423558;4424.380586717862;4424.579664931826;4432.318447339313;4436.977815110904;4275.315949448073;4385.232584297735;4404.625606823456;4506.211413002777;4507.09858028389;5064.949350506521;5091.643584949092;5103.5406813386235;5122.206058061007;5133.512316329943;6033.939660603338;6044.365341639064;6045.395460453919;6058.4244496772735;6095.329513753483;1487.3307011844713;1534.3862455018082;1541.5683608589613;1555.5566663500847;1558.9454369097027;1022.987242629806;1056.8097742406442;1067.2902519824386;1073.3506228731505;1078.5955266774959;5742.310922689304;5773.220615216976;5785.450382213683;5792.594518383757;5797.768089276382;6107.572469927062;6136.693165341715;6141.324454310023;6151.231096112986;6161;5623.818998010382;5686.545076393879;5710.971445142831;5714.085781263323;5715.31416230055;3200.6799320067807;3233.5958005249345;3237.417264892295;3248.642649308574;3249.1552196429097;6153.553914830011;6337.714802833883;6373.904391434132;6379.96810015947;6399.13611662429;6209.161763162006;6225.968870155621;6245.375462453719;6248.31268560458;6258.718357673915;6316.557840951089;6327.36726327382;6331.715072821642;6360.180434902105;6399.456046236029;5948.3047331510315;6125.571210015256;6152;6152.846178845564;6207.441330280297;5817.680027598353;5931.970340148272;5949.613275137102;6155.169052177898;6155.692215389123;6406.23125224976;6408.455281300955;6424.325445828307;6427.260865000398;6446.484281257582;4382.6055654991205;4423.911521769611;4434.800433980414;4452.287633978548;4470.083632855322;6144.446999770043;6188.628682278898;6188.659623720739;6195;6198.721057552511;6028.4272994065495;6033.245844269466;6035.969820150871;6045.153678485103;6050;5853.046134379931;5855.824325270249;5901.232839730979;5949;5996.790112346222;5956.731947062479;5969.641821490724;5970.641761494324;6012.939870601238;6019;5788.623739456922;5991.64050156992;6053.486628342915;6077.969610151921;6089.756409743561;5836.416358364129;5841.4450627190345;5877.382874798084;5892.499137573354;5894.145348924468 19:33:57 INFO - PID 5088 | |5;yui.html;673.7305077968812;684.9486288528361;687.5049964025902;700.8668353012924;723.3020135569182;13774.586762397119;14320.57038288853;14668.11991280524;15168.317425715843;15345.846541534582;14665.486707965223;14754.409823607042;15498.61253468661;15656.843431565681;15818.84181158191;799.8240387114826;813.4793732011517;816.9060558035843;816.9305609023211;817.7669364231195;794.6781553470825;819.6598411659154;820.1429506166041;825.6986200036973;825.8761185822115;852.5140669818206;854.5855260198798;855.0850589868843;855.422589751918;855.5422848775906;845.9196376344238;851.8253757979661;852.0627309959058;852.0754980845759;852.1989330029752;621.710904429438;624.6252248650818;624.7188765055697;624.8875202463555;625.1341891480332;14126.434942602284;14134.787978180335;14154.221517816515;14186;15332.386704531806;14466.638334041629;14831.406743730238;15191.620209494851;15199.772003419957;15579.532614021484;11970.162088653798;12038.518459261619;12221.450034748368;12264.448099835528;12284.754304913851;3171.619405671311;3191.266008817962;3198.8720451181925;3228.983855080733;3231;82783.5860820692;84925.25969090444;86322.13677863203;86836.56581717114;87829.56085219597;11988.700282492833;11996.160268781276;12043.939780301218;12173.817392739116;12213.267203967791;25458.745412546006;25683.61474577883;25721.614175787377;25744.227673169837;25763;8050.718224862218;8063.435559510778;8187.877181842277;8243.958780206061;8358.665653373797;4009.2382447335494;4062.6546743527133;4071.8778436646357;4085.182963407271;4129.256733787887;4173.812178451977;4207.821809893235;4212.178625168124;4242.321228603409;4260.808263628082;718.2530168624572;736.4881407421898;737.7417903733758;750.4876189344699;751.6767789850417;518.3132349636746;532.5127508329912;532.8934213157384;536.2064145065248;537.159345624097;9348.626054957862;9992.10071093605;10123.1901447884;10141.290109692398;10162.339447935861;516.8914527949173;518.9792408303625;536.9543588794996;537.1834811087126;541.7941182350681;4330.523642399334;4439.7336159829865;4531.456225252925;4668.4631267404;4892.645545464539;65338.673306633165;67188.66405667941;72569;72927.63536182391;74083;56462.717686411306;60345.698271508365;62197.689011554656;63231;68033.65983170053;46048.54635902254;52342.47657523376;55047;56245;59380.81238375208;9434.905650943678;9990.500474976367;10016.449095299828;10019;10036;30628;30757.538636920017;31225.8438707805;31354.21614459657;32629.510557342135;37905.62094379021;38137.318175048626;38158;38682;39022.2195556093;10763.515641796295;11042.613508527164;11232.382218977707;11986.940065299617;12145.332006739362;11978.959509983379;13000.479980800663;13079.149855259378;13228.40472178735;13495;12494.687632809253;12792.232466052066;13062.80405793933;13308.268045257604;13330.066895317235;11948.880511195124;12140.75718485643;12172.452239649414;12313;12771.808422873479;12449.93775031119;13290.136141150795;13374.398152083375;13594.11638243511;13617.25105119228;10978.286411383235;11181;11201.159913006393;11429.542818287508;11737.589184378507;10189.184865210616;10529.736756581147;10646.8935310649;10764.946175269073;10770.676879693621 19:33:57 INFO - PID 5088 | __end_tp_report 19:33:57 INFO - PID 5088 | __start_cc_report 19:33:57 INFO - PID 5088 | _x_x_mozilla_cycle_collect,1541 19:33:57 INFO - PID 5088 | __end_cc_report 19:33:57 INFO - PID 5088 | __startTimestamp1490063637220__endTimestamp 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | ------- Summary: start ------- 19:33:57 INFO - PID 5088 | Number of tests: 6 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | [#0] dojo.html Cycles:165 Average:5777.48 Median:6045.97 stddev:684.57 (11.3%) stddev-sans-first:658.14 19:33:57 INFO - PID 5088 | Values: 3284.3 3314.3 3335.2 3347.6 3372.6 5678.7 5715.8 5757.8 5763.0 5795.0 5680.3 5688.0 5709.1 5773.7 5869.9 5055.9 5110.9 5172.3 5247.2 5250.4 5221.9 5270.4 5276.9 5281.6 5331.2 5123.0 5144.7 5167.5 5168.6 5200.8 5210.9 5215.1 5223.9 5300.2 5304.6 5122.4 5190.1 5194.8 5232.8 5241.7 5585.2 5648.5 5809.0 5816.5 5822.3 6153.9 6159.7 6209.1 6231.9 6239.3 6328.1 6337.8 6354.4 6354.7 6357.0 5859.3 5860.7 5870.1 5874.0 5877.1 6245.7 6301.0 6325.8 6345.0 6395.6 6037.3 6087.0 6088.4 6097.8 6111.1 6174.6 6259.4 6288.5 6291.6 6292.5 6131.2 6281.9 6295.8 6302.3 6305.1 6103.4 6122.4 6143.7 6201.0 6203.1 4780.0 4780.2 4786.5 4812.8 4816.9 4222.9 4227.2 4230.8 4231.9 4269.2 5849.9 5857.1 5911.9 5957.0 5972.3 6169.0 6194.3 6221.8 6225.4 6233.1 5850.9 5855.5 5857.8 5863.1 5901.7 5206.7 5233.2 5240.6 5291.9 5325.5 6561.2 6592.8 6595.1 6599.2 6599.6 6305.1 6342.7 6360.5 6364.1 6367.2 6248.0 6383.5 6558.0 6567.0 6571.8 6127.0 6127.0 6149.9 6172.8 6177.6 6245.4 6312.1 6321.2 6321.8 6329.4 5990.6 5995.7 6035.2 6061.9 6153.9 6098.1 6123.1 6129.9 6143.2 6148.0 6107.0 6121.1 6131.2 6134.6 6136.3 6042.9 6053.7 6074.3 6141.8 6183.2 5922.1 6005.3 6046.0 6050.5 6052.7 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | [#1] ext.html Cycles:180 Average:39056.63 Median:150673.29 stddev:62260.01 (41.3%) stddev-sans-first:62367.83 19:33:57 INFO - PID 5088 | Values: 636.4 674.7 679.7 696.9 710.7 29797.7 36732.3 37238.8 37296.3 37391.8 38913.0 39276.6 39322.0 39337.6 39387.2 2110.8 2155.2 2419.5 2474.9 2489.1 2595.9 2673.4 2675.2 2675.2 2681.5 2607.9 2654.9 2660.4 2667.1 2670.3 2642.8 2662.4 2666.1 2668.4 2668.9 2404.6 2419.4 2425.0 2435.6 2436.6 36522.5 37170.3 37178.0 37182.6 37200.3 2966.1 2973.7 3012.1 3018.1 3028.8 27829.6 27903.9 27939.7 28182.9 28788.4 3632.0 3661.7 3677.1 3709.9 3723.9 202159.0 202395.0 202400.0 202570.0 202984.0 48938.5 49572.3 49581.3 49598.0 49618.3 51571.7 51679.7 51755.0 51761.7 51842.7 14130.4 14391.6 14421.3 14458.8 14475.6 10219.3 10356.1 11146.3 11968.9 12124.1 13144.7 13317.0 13398.7 13443.7 13534.7 5110.5 5114.3 5196.5 5325.8 5350.8 1031.6 1035.2 1055.8 1060.7 1078.2 24785.6 24852.3 25136.5 25170.2 25268.7 1147.5 1154.4 1171.4 1174.5 1187.8 12164.7 12831.5 13125.3 13152.3 13205.8 271556.0 272762.0 272832.0 272952.0 273517.0 52116.5 52157.0 52169.0 52173.5 52235.7 1440.2 1466.5 1482.8 1488.2 1488.3 23435.5 24599.0 26101.6 29094.3 29664.0 48078.3 48114.3 48153.8 48160.8 48233.0 231206.0 231574.0 231735.0 231804.0 232074.0 25842.0 26042.6 26063.9 31528.2 31658.0 37627.4 37766.0 37774.2 37784.4 38136.8 33905.8 33907.5 33982.0 34344.0 34501.0 33602.8 33765.8 33966.0 34106.0 35071.5 35221.8 35245.0 35537.6 36004.6 36203.5 29147.4 30658.7 30917.1 30987.0 31072.0 24340.9 24382.0 24514.0 24874.3 24966.3 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | [#2] jquery.html Cycles:180 Average:31781.26 Median:10987.56 stddev:88557.46 (806.0%) stddev-sans-first:88774.60 19:33:57 INFO - PID 5088 | Values: 431.8 443.4 452.6 460.4 464.5 18176.2 19395.4 19855.6 19944.4 19979.5 19031.8 19265.4 19295.3 19375.1 19493.7 4349.6 4508.5 4511.9 4514.9 4517.3 4424.1 4471.0 4483.1 4492.8 4496.6 4316.4 4420.2 4444.9 4457.2 4464.7 4388.7 4439.6 4442.6 4444.9 4447.6 3893.8 4008.6 4044.4 4132.4 4134.7 25765.6 26104.2 26119.9 26158.0 26268.6 1970.8 1977.5 1983.2 1995.6 2024.9 16098.6 16197.4 16386.3 16457.3 16811.1 1718.2 1739.8 1758.7 1779.2 1831.5 95618.0 98783.1 99049.0 99188.5 99572.0 12189.8 12299.9 12410.6 12523.5 12566.2 17579.8 17590.4 17595.1 17648.3 17651.7 8643.6 8887.2 8978.9 9059.2 9107.7 8189.8 8249.6 8263.9 8387.4 8401.2 7751.3 7751.7 7759.3 7811.2 7829.3 2192.4 2238.3 2247.9 2264.7 2363.0 966.0 987.3 987.8 1000.7 1011.5 19226.8 19498.5 19629.3 19654.9 19724.5 1003.6 1009.4 1012.7 1017.9 1041.3 10298.8 10630.8 10682.0 10784.4 10814.8 521324.0 524359.0 536382.0 549135.0 550643.0 93127.0 93619.5 93703.5 94268.0 94474.0 1209.4 1234.6 1257.2 1265.4 1278.9 18062.0 19272.2 20282.8 20453.6 20474.5 38624.0 38758.4 41458.4 47779.0 48544.0 62610.4 62908.7 67744.7 69005.0 69824.0 11764.8 12224.3 12968.7 13345.4 13877.9 8640.6 13801.7 13803.5 14744.9 14861.7 11882.5 12433.6 12491.6 12546.2 12707.2 12002.9 12079.5 12201.3 12424.8 12703.0 11543.2 11555.8 11707.5 11885.9 12582.9 13097.7 13183.2 13201.7 13229.1 13364.3 12237.8 12387.2 12414.2 12475.9 13083.9 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | [#3] mootools.html Cycles:180 Average:6943.07 Median:2401.31 stddev:4459.66 (185.7%) stddev-sans-first:4451.25 19:33:57 INFO - PID 5088 | Values: 1195.6 1327.0 1365.2 1379.8 1390.6 9209.7 10418.9 10624.7 10657.7 10679.8 10735.9 10791.9 10798.8 10825.5 10830.7 1966.7 2040.5 2390.6 2425.8 2428.0 2321.3 2389.4 2395.8 2398.8 2403.8 2295.4 2328.7 2339.9 2348.9 2351.4 2302.6 2330.3 2330.9 2336.0 2339.2 2070.8 2073.6 2076.2 2130.8 2280.1 10911.1 10957.7 10969.8 10971.9 10973.6 6058.9 6241.7 6253.8 6260.0 6263.2 3605.2 3766.5 3970.2 3992.1 4005.6 1304.5 1346.4 1374.0 1419.7 1429.6 16376.0 16436.8 16438.7 16445.6 16458.3 5359.2 5376.6 5541.7 5625.7 5655.8 13571.6 13656.7 13661.0 13698.1 13703.9 6425.1 6431.7 6432.8 6438.1 6438.3 4968.7 5122.7 5479.9 5594.7 5633.5 4370.5 4388.1 4398.6 4449.1 4463.8 1388.6 1413.9 1417.8 1428.3 1428.5 823.6 824.5 827.4 836.8 840.6 6169.8 6349.7 6463.8 6516.7 6581.9 601.6 617.1 619.6 623.7 626.0 3795.1 3823.0 3827.5 3840.3 3883.7 13968.7 14539.4 14552.5 14618.6 14653.6 14328.3 14462.3 14485.1 14509.9 14524.0 10206.7 10335.1 10402.1 10563.6 10563.7 6268.0 6298.8 6360.4 6421.2 6465.9 12555.0 12971.7 13024.0 13094.8 13172.7 12737.0 12979.2 12993.0 13024.2 13231.6 7904.9 8908.6 9129.5 9237.2 9324.3 10098.7 10118.1 10549.2 10552.8 10560.5 8668.3 8765.0 8773.1 8829.2 8991.6 8754.7 8796.9 8809.9 8998.1 9024.6 7799.8 7826.5 7846.7 7896.1 7933.2 6022.9 6215.6 6216.1 6232.3 6289.0 5964.7 5980.3 6070.0 6145.7 6193.8 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | [#4] prototype.html Cycles:180 Average:5044.60 Median:5716.76 stddev:1476.09 (25.8%) stddev-sans-first:1438.74 19:33:57 INFO - PID 5088 | Values: 414.7 415.1 430.8 437.3 437.5 5645.8 5718.2 5741.6 5748.5 5750.9 5399.6 5592.5 5629.6 5651.0 5667.1 4406.8 4407.0 4407.2 4420.9 4429.2 4431.8 4433.5 4446.6 4447.8 4448.7 4210.9 4379.1 4390.6 4399.3 4412.2 4376.4 4378.0 4380.6 4389.1 4394.0 3889.7 3899.1 3912.4 3923.1 3924.6 5378.2 5504.1 5548.4 5579.0 5583.2 5111.1 5114.8 5129.0 5131.9 5139.1 5976.6 5986.9 5991.2 5995.4 6010.7 4221.8 4264.9 4324.7 4340.9 4353.5 5913.6 6002.7 6121.8 6225.3 6284.3 4423.3 4424.4 4424.6 4432.3 4437.0 4275.3 4385.2 4404.6 4506.2 4507.1 5064.9 5091.6 5103.5 5122.2 5133.5 6033.9 6044.4 6045.4 6058.4 6095.3 1487.3 1534.4 1541.6 1555.6 1558.9 1023.0 1056.8 1067.3 1073.4 1078.6 5742.3 5773.2 5785.5 5792.6 5797.8 6107.6 6136.7 6141.3 6151.2 6161.0 5623.8 5686.5 5711.0 5714.1 5715.3 3200.7 3233.6 3237.4 3248.6 3249.2 6153.6 6337.7 6373.9 6380.0 6399.1 6209.2 6226.0 6245.4 6248.3 6258.7 6316.6 6327.4 6331.7 6360.2 6399.5 5948.3 6125.6 6152.0 6152.8 6207.4 5817.7 5932.0 5949.6 6155.2 6155.7 6406.2 6408.5 6424.3 6427.3 6446.5 4382.6 4423.9 4434.8 4452.3 4470.1 6144.4 6188.6 6188.7 6195.0 6198.7 6028.4 6033.2 6036.0 6045.2 6050.0 5853.0 5855.8 5901.2 5949.0 5996.8 5956.7 5969.6 5970.6 6012.9 6019.0 5788.6 5991.6 6053.5 6078.0 6089.8 5836.4 5841.4 5877.4 5892.5 5894.1 19:33:57 INFO - PID 5088 | 19:33:57 INFO - PID 5088 | [#5] yui.html Cycles:180 Average:16288.36 Median:21515.73 stddev:20615.61 (95.8%) stddev-sans-first:20640.10 19:33:57 INFO - PID 5088 | Values: 673.7 684.9 687.5 700.9 723.3 13774.6 14320.6 14668.1 15168.3 15345.8 14665.5 14754.4 15498.6 15656.8 15818.8 799.8 813.5 816.9 816.9 817.8 794.7 819.7 820.1 825.7 825.9 852.5 854.6 855.1 855.4 855.5 845.9 851.8 852.1 852.1 852.2 621.7 624.6 624.7 624.9 625.1 14126.4 14134.8 14154.2 14186.0 15332.4 14466.6 14831.4 15191.6 15199.8 15579.5 11970.2 12038.5 12221.5 12264.4 12284.8 3171.6 3191.3 3198.9 3229.0 3231.0 82783.6 84925.3 86322.1 86836.6 87829.6 11988.7 11996.2 12043.9 12173.8 12213.3 25458.7 25683.6 25721.6 25744.2 25763.0 8050.7 8063.4 8187.9 8244.0 8358.7 4009.2 4062.7 4071.9 4085.2 4129.3 4173.8 4207.8 4212.2 4242.3 4260.8 718.3 736.5 737.7 750.5 751.7 518.3 532.5 532.9 536.2 537.2 9348.6 9992.1 10123.2 10141.3 10162.3 516.9 519.0 537.0 537.2 541.8 4330.5 4439.7 4531.5 4668.5 4892.6 65338.7 67188.7 72569.0 72927.6 74083.0 56462.7 60345.7 62197.7 63231.0 68033.7 46048.5 52342.5 55047.0 56245.0 59380.8 9434.9 9990.5 10016.4 10019.0 10036.0 30628.0 30757.5 31225.8 31354.2 32629.5 37905.6 38137.3 38158.0 38682.0 39022.2 10763.5 11042.6 11232.4 11986.9 12145.3 11979.0 13000.5 13079.1 13228.4 13495.0 12494.7 12792.2 13062.8 13308.3 13330.1 11948.9 12140.8 12172.5 12313.0 12771.8 12449.9 13290.1 13374.4 13594.1 13617.3 10978.3 11181.0 11201.2 11429.5 11737.6 10189.2 10529.7 10646.9 10764.9 10770.7 19:33:57 INFO - PID 5088 | -------- Summary: end -------- 19:33:57 INFO - PID 5088 | 19:33:57 INFO - TEST-INFO | 5088: exit 0 19:33:57 INFO - TEST-OK | dromaeo_css | took 1116939ms 19:33:57 INFO - TEST-START | kraken 19:33:57 INFO - operating with platform_type : linux_ 19:33:57 INFO - Initialising browser for kraken test... 19:33:57 INFO - TEST-INFO | started process 5416 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv8aPJQ/profile http://localhost:51900/getInfo.html) 19:34:01 INFO - PID 5416 | __metrics Screen width/height:1600/1200 19:34:01 INFO - PID 5416 | colorDepth:24 19:34:01 INFO - PID 5416 | Browser inner width/height: 1024/697 19:34:01 INFO - PID 5416 | __metrics 19:34:02 INFO - PID 5416 | 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" 19:34:02 INFO - PID 5416 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:34:03 INFO - TEST-INFO | 5416: exit 0 19:34:03 INFO - Browser initialized. 19:34:03 INFO - Running cycle 1/1 for kraken test... 19:34:03 INFO - TEST-INFO | started process 5509 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv8aPJQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:15 INFO - PID 5509 | RSS: Main: 143953920 19:34:15 INFO - PID 5509 | 19:34:18 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51900/tests/kraken/driver.html?testName=audio-beat-detection) 19:34:19 INFO - PID 5509 | RSS: Main: 199929856 19:34:19 INFO - PID 5509 | 19:34:21 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51900/tests/kraken/driver.html?testName=audio-dft) 19:34:22 INFO - PID 5509 | RSS: Main: 181645312 19:34:22 INFO - PID 5509 | 19:34:25 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51900/tests/kraken/driver.html?testName=audio-fft) 19:34:25 INFO - PID 5509 | RSS: Main: 261050368 19:34:25 INFO - PID 5509 | 19:34:27 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51900/tests/kraken/driver.html?testName=audio-oscillator) 19:34:28 INFO - PID 5509 | RSS: Main: 172617728 19:34:28 INFO - PID 5509 | 19:34:30 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51900/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:34:31 INFO - PID 5509 | RSS: Main: 175947776 19:34:31 INFO - PID 5509 | 19:34:34 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51900/tests/kraken/driver.html?testName=imaging-darkroom) 19:34:34 INFO - PID 5509 | RSS: Main: 215191552 19:34:34 INFO - PID 5509 | 19:34:38 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51900/tests/kraken/driver.html?testName=imaging-desaturate) 19:34:38 INFO - PID 5509 | RSS: Main: 234188800 19:34:38 INFO - PID 5509 | 19:34:41 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51900/tests/kraken/driver.html?testName=json-parse-financial) 19:34:42 INFO - PID 5509 | RSS: Main: 252043264 19:34:42 INFO - PID 5509 | 19:34:43 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51900/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:34:44 INFO - PID 5509 | RSS: Main: 177520640 19:34:44 INFO - PID 5509 | 19:34:46 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:34:46 INFO - PID 5509 | RSS: Main: 183783424 19:34:46 INFO - PID 5509 | 19:34:48 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:34:49 INFO - PID 5509 | RSS: Main: 193462272 19:34:49 INFO - PID 5509 | 19:34:51 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:34:52 INFO - PID 5509 | RSS: Main: 202670080 19:34:52 INFO - PID 5509 | 19:34:55 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:34:56 INFO - PID 5509 | RSS: Main: 200056832 19:34:56 INFO - PID 5509 | 19:34:57 INFO - PID 5509 | Cycle 1(1): loaded http://localhost:51900/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51900/tests/kraken/driver.html?testName=ai-astar) 19:34:58 INFO - PID 5509 | RSS: Main: 268283904 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | __start_tp_report 19:34:58 INFO - PID 5509 | _x_x_mozilla_page_load 19:34:58 INFO - PID 5509 | _x_x_mozilla_page_load_details 19:34:58 INFO - PID 5509 | |i|pagename|runs| 19:34:58 INFO - PID 5509 | |0;ai-astar;101;107;108;108;107;109;107;108;107;108 19:34:58 INFO - PID 5509 | |1;audio-beat-detection;146;123;120;162;119;117;123;117;142;118 19:34:58 INFO - PID 5509 | |2;audio-dft;161;161;160;163;161;164;162;164;162;162 19:34:58 INFO - PID 5509 | |3;audio-fft;74;74;74;111;74;74;74;75;92;78 19:34:58 INFO - PID 5509 | |4;audio-oscillator;82;83;119;83;83;83;99;84;83;104 19:34:58 INFO - PID 5509 | |5;imaging-gaussian-blur;113;113;113;113;112;114;113;113;113;113 19:34:58 INFO - PID 5509 | |6;imaging-darkroom;147;147;148;147;147;146;147;147;147;148 19:34:58 INFO - PID 5509 | |7;imaging-desaturate;94;94;94;94;94;93;93;94;94;94 19:34:58 INFO - PID 5509 | |8;json-parse-financial;57;70;55;55;56;56;56;56;57;57 19:34:58 INFO - PID 5509 | |9;json-stringify-tinderbox;54;54;54;55;55;55;54;55;54;54 19:34:58 INFO - PID 5509 | |10;stanford-crypto-aes;80;79;80;79;79;78;78;83;79;87 19:34:58 INFO - PID 5509 | |11;stanford-crypto-ccm;128;126;125;125;134;129;134;127;169;126 19:34:58 INFO - PID 5509 | |12;stanford-crypto-pbkdf2;183;186;183;187;184;189;187;181;186;189 19:34:58 INFO - PID 5509 | |13;stanford-crypto-sha256-iterative;66;66;68;66;76;67;64;65;64;66 19:34:58 INFO - PID 5509 | __end_tp_report 19:34:58 INFO - PID 5509 | __start_cc_report 19:34:58 INFO - PID 5509 | _x_x_mozilla_cycle_collect,1742 19:34:58 INFO - PID 5509 | __end_cc_report 19:34:58 INFO - PID 5509 | __startTimestamp1490063698223__endTimestamp 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | ------- Summary: start ------- 19:34:58 INFO - PID 5509 | Number of tests: 14 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#0] ai-astar Cycles:10 Average:107.00 Median:108.00 stddev:2.21 (2.0%) stddev-sans-first:0.71 19:34:58 INFO - PID 5509 | Values: 101.0 107.0 108.0 108.0 107.0 109.0 107.0 108.0 107.0 108.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:123.00 stddev:15.66 (12.7%) stddev-sans-first:15.31 19:34:58 INFO - PID 5509 | Values: 146.0 123.0 120.0 162.0 119.0 117.0 123.0 117.0 142.0 118.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 19:34:58 INFO - PID 5509 | Values: 161.0 161.0 160.0 163.0 161.0 164.0 162.0 164.0 162.0 162.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#3] audio-fft Cycles:10 Average:80.00 Median:74.00 stddev:12.25 (16.6%) stddev-sans-first:12.80 19:34:58 INFO - PID 5509 | Values: 74.0 74.0 74.0 111.0 74.0 74.0 74.0 75.0 92.0 78.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:83.00 stddev:12.75 (15.4%) stddev-sans-first:13.16 19:34:58 INFO - PID 5509 | Values: 82.0 83.0 119.0 83.0 83.0 83.0 99.0 84.0 83.0 104.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 19:34:58 INFO - PID 5509 | Values: 113.0 113.0 113.0 113.0 112.0 114.0 113.0 113.0 113.0 113.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 19:34:58 INFO - PID 5509 | Values: 147.0 147.0 148.0 147.0 147.0 146.0 147.0 147.0 147.0 148.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:34:58 INFO - PID 5509 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:56.50 stddev:4.45 (7.9%) stddev-sans-first:4.72 19:34:58 INFO - PID 5509 | Values: 57.0 70.0 55.0 55.0 56.0 56.0 56.0 56.0 57.0 57.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 19:34:58 INFO - PID 5509 | Values: 54.0 54.0 54.0 55.0 55.0 55.0 54.0 55.0 54.0 54.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:79.50 stddev:2.78 (3.5%) stddev-sans-first:2.95 19:34:58 INFO - PID 5509 | Values: 80.0 79.0 80.0 79.0 79.0 78.0 78.0 83.0 79.0 87.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:128.50 stddev:13.32 (10.4%) stddev-sans-first:14.03 19:34:58 INFO - PID 5509 | Values: 128.0 126.0 125.0 125.0 134.0 129.0 134.0 127.0 169.0 126.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.50 Median:186.50 stddev:2.68 (1.4%) stddev-sans-first:2.68 19:34:58 INFO - PID 5509 | Values: 183.0 186.0 183.0 187.0 184.0 189.0 187.0 181.0 186.0 189.0 19:34:58 INFO - PID 5509 | 19:34:58 INFO - PID 5509 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.00 stddev:3.46 (5.2%) stddev-sans-first:3.66 19:34:58 INFO - PID 5509 | Values: 66.0 66.0 68.0 66.0 76.0 67.0 64.0 65.0 64.0 66.0 19:34:58 INFO - PID 5509 | -------- Summary: end -------- 19:34:58 INFO - PID 5509 | 19:34:59 INFO - TEST-INFO | 5509: exit 0 19:34:59 INFO - TEST-OK | kraken | took 61359ms 19:34:59 INFO - SUITE-END | took 1178s 19:34:59 INFO - Completed test suite (00:19:38) 19:34:59 INFO - javascript benchmark 19:34:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7142.549335201086, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.277458959029, 3314.3205642843204, 3335.166208447887, 3347.581552305962, 3372.6121496027963, 5678.716064196784, 5715.771369145229, 5757.769689212431, 5763.049096899012, 5795.043817770063, 5680.289963754531, 5688.004599195135, 5709.0580054290995, 5773.740181691824, 5869.882602347961, 5055.949440505603, 5110.897782044357, 5172.250023746573, 5247.239150323201, 5250.4487028861995, 5221.947780522184, 5270.420253772082, 5276.868078298035, 5281.551068159211, 5331.200319952019, 5122.975404918994, 5144.717040562787, 5167.534921857051, 5168.638195326329, 5200.817971371002, 5210.895782084337, 5215.139501982168, 5223.947760522422, 5300.2314664373835, 5304.575633949275, 5122.385313762373, 5190.143626301656, 5194.818181363634, 5232.816851410183, 5241.711705856176, 5585.2459917910965, 5648.548116150698, 5809.04150815115, 5816.505597024258, 5822.330432000332, 6153.876922461525, 6159.722812473449, 6209.130721698965, 6231.906521402183, 6239.2824825145235, 6328.050792381134, 6337.809865704036, 6354.364563543655, 6354.650494222816, 6357.046443033536, 5859.267591551056, 5860.677662728505, 5870.148828419855, 5873.970630146865, 5877.147813567009, 6245.656488893063, 6300.968495157586, 6325.810225693236, 6345.016522438937, 6395.6482393468805, 6037.275526936708, 6087.0, 6088.4215999479975, 6097.796915383898, 6111.105587097633, 6174.567780255339, 6259.436650701458, 6288.52836037299, 6291.591046582049, 6292.528060395489, 6131.172291740558, 6281.8743625127245, 6295.779647712399, 6302.338254483305, 6305.117283580211, 6103.428585615297, 6122.367660829284, 6143.72682107623, 6201.0, 6203.069539569011, 4780.0, 4780.235162374003, 4786.473487916326, 4812.759362031885, 4816.903661926742, 4222.852200173041, 4227.196832601796, 4230.830766769356, 4231.851885184065, 4269.188854117708, 5849.88300233993, 5857.121431785266, 5911.852203694942, 5957.0, 5972.343042265347, 6168.9691551541955, 6194.34959329273, 6221.751129954841, 6225.439710426083, 6233.096201050822, 5850.8864904136035, 5855.473007429352, 5857.824265272048, 5863.061910094364, 5901.675407852609, 5206.687598744087, 5233.241180028875, 5240.633155679142, 5291.894162116736, 5325.4674532547215, 6561.212654481397, 6592.76925307612, 6595.142631457879, 6599.208095028627, 6599.571027883172, 6305.085762564494, 6342.682865856596, 6360.49116070704, 6364.077208804605, 6367.204099487564, 6248.0, 6383.457406120346, 6558.04908288286, 6567.0, 6571.80284591463, 6126.975740326223, 6127.0062805303205, 6149.907751383822, 6172.814815555541, 6177.598456100339, 6245.437910588069, 6312.116303717575, 6321.1782468278825, 6321.810345689637, 6329.430351268224, 5990.550708696952, 5995.700214989321, 6035.155078289131, 6061.939380606137, 6153.907691384543, 6098.099428985257, 6123.069106197319, 6129.859247658277, 6143.187759611015, 6148.0, 6106.969465152646, 6121.056693971993, 6131.162586540671, 6134.601250918676, 6136.32500424944, 6042.909356359746, 6053.727582258721, 6074.271087469532, 6141.829680688692, 6183.196184495987, 5922.111683247375, 6005.339412664516, 6045.969770151297, 6050.485708714808, 6052.66710330936], "value": 5729.549471115413, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.3636363636364, 674.7166190200119, 679.6601699150425, 696.940760035397, 710.6730903784261, 29797.702022979764, 36732.26535469292, 37238.813805930935, 37296.254074918485, 37391.81304093482, 38913.0, 39276.60723392765, 39322.0, 39337.60662393375, 39387.212255754865, 2110.757262914769, 2155.181031208139, 2419.540287345403, 2474.925752227436, 2489.0541594194183, 2595.9351016224555, 2673.4252135790803, 2675.1573254424893, 2675.210812810216, 2681.4905168018013, 2607.9348016299555, 2654.86725663717, 2660.401409682825, 2667.1065193160316, 2670.3190686375046, 2642.7753640940446, 2662.361033352001, 2666.1201803405024, 2668.43962767819, 2668.8799004044863, 2404.5671779079757, 2419.4072452249134, 2424.963625545618, 2435.585950388438, 2436.6223235398543, 36522.452163217305, 37170.256594868224, 37178.0, 37182.628173718185, 37200.25599488022, 2966.1142715805095, 2973.717496837797, 3012.0662594595747, 3018.0644000359957, 3028.787984841062, 27829.582556261674, 27903.907866718626, 27939.720602793914, 28182.859085704644, 28788.424231515462, 3631.963680363215, 3661.743677942545, 3677.0623491009887, 3709.9443508347395, 3723.94414083789, 202159.0, 202395.0, 202400.0, 202570.0, 202983.98508007513, 48938.51061489339, 49572.25641615379, 49581.25628115581, 49598.0, 49618.255726164134, 51571.742141289054, 51679.74160129175, 51755.0, 51761.741191293804, 51842.74078629583, 14130.434782608787, 14391.64020899465, 14421.278936053155, 14458.783118253235, 14475.638109047148, 10219.284650074573, 10356.067953884187, 11146.27549209315, 11968.940155299168, 12124.090693198046, 13144.671383215496, 13317.0, 13398.665033374049, 13443.731125377437, 13534.661633459242, 5110.540051395392, 5114.258432527263, 5196.467042222554, 5325.760340784674, 5350.785968561292, 1031.6234574380446, 1035.1936020664164, 1055.7782865598156, 1060.671191930504, 1078.245228340165, 24785.62821557678, 24852.25443236706, 25136.49727005486, 25170.24489265325, 25268.747312527008, 1147.5041784119508, 1154.4458659843322, 1171.4435643069644, 1174.4656181437426, 1187.8337032815414, 12164.695882603006, 12831.4602247729, 13125.34373281332, 13152.342382880817, 13205.801912971314, 271556.0, 272762.0, 272832.0, 272952.0, 273517.0, 52116.47883521192, 52157.0, 52168.95719077952, 52173.47826521762, 52235.73882130565, 1440.1503113163285, 1466.516049703574, 1482.844301348343, 1488.151753500516, 1488.3376897280823, 23435.531289373776, 24599.0, 26101.608475872497, 29094.27264318409, 29664.0, 48078.27882581694, 48114.27828582504, 48153.75923120502, 48160.75919620379, 48233.03533929231, 231206.0, 231574.0, 231735.0, 231804.0, 232074.0, 25842.0, 26042.609360859224, 26063.869680651474, 31528.211794705316, 31658.0, 37627.435588466746, 37766.0, 37774.24451511009, 37784.43323350097, 38136.809315953244, 33905.83047084749, 33907.49138762871, 33982.0, 34344.0, 34501.0, 33602.83198583991, 33765.83117084399, 33966.0, 34106.0, 35071.47392789161, 35221.82389088038, 35245.0, 35537.64462355343, 36004.639953601174, 36203.456948146326, 29147.417051658424, 30658.693413065583, 30917.0724878254, 30987.0, 31072.0, 24340.878295608407, 24382.0, 24514.0, 24874.25377238686, 24966.251012469656], "value": 14440.770902959603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.7754767520887, 443.35713215837035, 452.57005844447764, 460.433666590094, 464.505301853526, 18176.18207180677, 19395.418137455883, 19855.602887942234, 19944.401667949947, 19979.500512487197, 19031.809681903178, 19265.422037338867, 19295.324663636777, 19375.128119234632, 19493.707594386095, 4349.565043495649, 4508.481524624677, 4511.932321015187, 4514.932276015862, 4517.322401639747, 4424.137293227822, 4470.97764511177, 4483.08096840147, 4492.797824097907, 4496.64026877849, 4316.352547117926, 4420.204363214636, 4444.911101777962, 4457.219986502365, 4464.6874718769695, 4388.714733542325, 4439.5560443955665, 4442.577955094261, 4444.888877778065, 4447.555244475527, 3893.8442462301473, 4008.5590585035625, 4044.4337792709043, 4132.359484279941, 4134.73124246923, 25765.61351579709, 26104.216873493635, 26119.869400652875, 26158.0, 26268.635822776043, 1970.7733610634816, 1977.5253939054667, 1983.2166294313809, 1995.6108558831033, 2024.8785072895673, 16098.597535061717, 16197.433089841918, 16386.26261818221, 16457.341706331732, 16811.075390853497, 1718.2096235731578, 1739.782527184102, 1758.6658534878331, 1779.1904683369037, 1831.5146486181172, 95618.0, 98783.06084695736, 99049.0, 99188.50405747998, 99572.0, 12189.75620487603, 12299.877001229874, 12410.627681169579, 12523.499060037497, 12566.246025238515, 17579.82420175782, 17590.38433654816, 17595.120243988007, 17648.294068237126, 17651.73522397165, 8643.611037503328, 8887.200151986352, 8978.86531702025, 9059.184673379295, 9107.726768196964, 8189.795255118551, 8249.628766705513, 8263.917360826315, 8387.412881098386, 8401.201885820838, 7751.263629955145, 7751.689932402754, 7759.301662850257, 7811.218878112257, 7829.256220659028, 2192.397090800043, 2238.317313219484, 2247.8876056197123, 2264.682944387787, 2362.9527409452057, 965.9903400966041, 987.3088837813559, 987.8123156600223, 1000.6997900629782, 1011.4790882695418, 19226.80773192278, 19498.512537186685, 19629.31297404584, 19654.901725491567, 19724.50688732793, 1003.5935446144323, 1009.3741880034279, 1012.6556970630021, 1017.9440130792877, 1041.265907535186, 10298.845517317246, 10630.787384252271, 10681.980480837994, 10784.352938823695, 10814.837777433344, 521324.0, 524359.0, 536382.0, 549135.0, 550643.0, 93127.0, 93619.53190234005, 93703.53148234352, 94268.0, 94474.0, 1209.4013463335705, 1234.5864135514703, 1257.1514227896134, 1265.373640048182, 1278.910476266671, 18062.0, 19272.22911083541, 20282.79717202868, 20453.590928181056, 20474.488137796674, 38624.0, 38758.4186237201, 41458.37812432756, 47779.0, 48544.0, 62610.373896260455, 62908.68545657242, 67744.6612766933, 69005.0, 69824.0, 11764.764704706029, 12224.266544007389, 12968.740625187633, 13345.399457024263, 13877.930610346884, 8640.611172497378, 13801.723965520834, 13803.51687690926, 14744.926275368984, 14861.702765944405, 11882.524699012289, 12433.626991190278, 12491.625251242478, 12546.1844980076, 12707.174033687781, 12002.939985300367, 12079.51681932713, 12201.328926909106, 12424.75150497003, 12703.0, 11543.249688770204, 11555.768884622428, 11707.473163707484, 11885.91996400609, 12582.874171258536, 13097.67255818574, 13183.209007459584, 13201.669958251121, 13229.140105893086, 13364.331783410596, 12237.816432753338, 12387.19483233587, 12414.193077449936, 12475.875241247471, 13083.869161308647], "value": 9766.443775919257, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1195.6114262864571, 1326.966825829354, 1365.160426337802, 1379.8206233189687, 1390.5550223928335, 9209.677661281856, 10418.947905260473, 10624.734381640443, 10657.680269591914, 10679.83980240297, 10735.946320268387, 10791.946040269788, 10798.838017429745, 10825.512851921663, 10830.72923176921, 1966.7246585478044, 2040.4898775306174, 2390.6175011998084, 2425.7938075263623, 2427.9757202427927, 2321.3384185507134, 2389.4026493376655, 2395.784379405853, 2398.8080953523677, 2403.8197135214928, 2295.357299956015, 2328.685627440291, 2339.9298021059312, 2348.906043758256, 2351.43565544269, 2302.6200676888293, 2330.3125577954543, 2330.8718020508863, 2335.9532809343887, 2339.228054741931, 2070.823979961705, 2073.6163809695254, 2076.2421716073677, 2130.7549631792385, 2280.1221529711015, 10911.127109831194, 10957.726056848564, 10969.83545246822, 10971.890281097167, 10973.615923442721, 6058.909116363258, 6241.65670888101, 6253.843653908643, 6259.998400255937, 6263.185785847811, 3605.170810713524, 3766.462103029153, 3970.2059588082498, 3992.1017771001584, 4005.61946615074, 1304.4782087165115, 1346.4344975110478, 1373.9725205495834, 1419.7089596632666, 1429.5997120806087, 16376.0, 16436.75344869828, 16438.67122657552, 16445.58886027847, 16458.259378328003, 5359.22291267764, 5376.569874410038, 5541.695206763586, 5625.6905870176715, 5655.830325090254, 13571.59285221445, 13656.726865462635, 13661.0, 13698.109622874481, 13703.931480342535, 6425.068365087036, 6431.678416079178, 6432.807015789534, 6438.066480360321, 6438.323975982548, 4968.726720030433, 5122.743862806845, 5479.945200548023, 5594.664320140804, 5633.521150702154, 4370.497392799869, 4388.144311859157, 4398.648108151404, 4449.065696203768, 4463.821447142078, 1388.5556621881103, 1413.9080959737585, 1417.8298604167567, 1428.271581493425, 1428.5357258890901, 823.625250511016, 824.5258976088772, 827.3877330775304, 836.8200836820113, 840.6133178737726, 6169.81490555284, 6349.650769207646, 6463.806085817433, 6516.696660667885, 6581.901271480931, 601.6420229963165, 617.1051974636795, 619.5724949784634, 623.7037407231529, 625.998402555916, 3795.1460921292487, 3822.9929287124482, 3827.483289755903, 3840.347140985983, 3883.650471457582, 13968.720625587635, 14539.41842326295, 14552.49066282675, 14618.561443156723, 14653.560393188221, 14328.283585820667, 14462.349194286282, 14485.130892146293, 14509.85490145106, 14524.0, 10206.744831379126, 10335.06984371395, 10402.063814256753, 10563.626728525243, 10563.735906602396, 6267.96866015667, 6298.84252893663, 6360.363963603602, 6421.197350331208, 6465.935340646722, 12555.0, 12971.740565188455, 13024.0, 13094.803577946148, 13172.73654526885, 12737.0, 12979.221246725227, 12993.0, 13024.153430027018, 13231.603051908458, 7904.881426778488, 8908.643654253758, 9129.45223286605, 9237.168654821098, 9324.347295689508, 10098.697039088838, 10118.08937195656, 10549.156067514426, 10552.841707374242, 10560.524776384927, 8668.263197628272, 8765.0358460568, 8773.078826723358, 8829.205371516595, 8991.55042247898, 8754.69358572447, 8796.91203087961, 8809.867851982097, 8998.14517620825, 9024.59389327495, 7799.766007019572, 7826.491278066907, 7846.725364612211, 7896.052473703192, 7933.206679332021, 6022.939770602238, 6215.595986260879, 6216.067589861376, 6232.25212974446, 6288.968555157195, 5964.731587078679, 5980.282366116094, 6070.0, 6145.723442445012, 6193.84515387119], "value": 5190.525184630867, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.6972709921759, 415.1303020172739, 430.8492027790273, 437.3112348051818, 437.5362116156874, 5645.774169033239, 5718.1994520767075, 5741.598088133822, 5748.5401167906575, 5750.913736293954, 5399.649022813524, 5592.468715472034, 5629.60592758507, 5650.971745141269, 5667.149927510875, 4406.810161256459, 4406.955930440703, 4407.184670835905, 4420.916875365524, 4429.202743506167, 4431.778411079449, 4433.534478879704, 4446.644268458532, 4447.822087116528, 4448.666350023761, 4210.863066971916, 4379.1022840317655, 4390.648748100144, 4399.27411977025, 4412.183746006983, 4376.387305777193, 4377.978110109461, 4380.6057454829215, 4389.144116897206, 4393.978030109861, 3889.7358358533306, 3899.083715326896, 3912.3544615138467, 3923.117298607819, 3924.5879182685694, 5378.247045413606, 5504.119340905476, 5548.41998470713, 5578.972105139489, 5583.246261754652, 5111.131107711698, 5114.769835357418, 5128.974205158983, 5131.923021154686, 5139.12634852076, 5976.64140151588, 5986.8503287417725, 5991.221141251653, 5995.400459954013, 6010.699465026732, 4221.831126754926, 4264.914701705949, 4324.7025892232205, 4340.8697739067875, 4353.499347575006, 5913.615614985012, 6002.658963004432, 6121.785737499166, 6225.346338634468, 6284.277308109535, 4423.270160423558, 4424.380586717862, 4424.579664931826, 4432.318447339313, 4436.977815110904, 4275.315949448073, 4385.232584297735, 4404.625606823456, 4506.211413002777, 4507.09858028389, 5064.949350506521, 5091.643584949092, 5103.5406813386235, 5122.206058061007, 5133.512316329943, 6033.939660603338, 6044.365341639064, 6045.395460453919, 6058.4244496772735, 6095.329513753483, 1487.3307011844713, 1534.3862455018082, 1541.5683608589613, 1555.5566663500847, 1558.9454369097027, 1022.987242629806, 1056.8097742406442, 1067.2902519824386, 1073.3506228731505, 1078.5955266774959, 5742.310922689304, 5773.220615216976, 5785.450382213683, 5792.594518383757, 5797.768089276382, 6107.572469927062, 6136.693165341715, 6141.324454310023, 6151.231096112986, 6161.0, 5623.818998010382, 5686.545076393879, 5710.971445142831, 5714.085781263323, 5715.31416230055, 3200.6799320067807, 3233.5958005249345, 3237.417264892295, 3248.642649308574, 3249.1552196429097, 6153.553914830011, 6337.714802833883, 6373.904391434132, 6379.96810015947, 6399.13611662429, 6209.161763162006, 6225.968870155621, 6245.375462453719, 6248.31268560458, 6258.718357673915, 6316.557840951089, 6327.36726327382, 6331.715072821642, 6360.180434902105, 6399.456046236029, 5948.3047331510315, 6125.571210015256, 6152.0, 6152.846178845564, 6207.441330280297, 5817.680027598353, 5931.970340148272, 5949.613275137102, 6155.169052177898, 6155.692215389123, 6406.23125224976, 6408.455281300955, 6424.325445828307, 6427.260865000398, 6446.484281257582, 4382.6055654991205, 4423.911521769611, 4434.800433980414, 4452.287633978548, 4470.083632855322, 6144.446999770043, 6188.628682278898, 6188.659623720739, 6195.0, 6198.721057552511, 6028.4272994065495, 6033.245844269466, 6035.969820150871, 6045.153678485103, 6050.0, 5853.046134379931, 5855.824325270249, 5901.232839730979, 5949.0, 5996.790112346222, 5956.731947062479, 5969.641821490724, 5970.641761494324, 6012.939870601238, 6019.0, 5788.623739456922, 5991.64050156992, 6053.486628342915, 6077.969610151921, 6089.756409743561, 5836.416358364129, 5841.4450627190345, 5877.382874798084, 5892.499137573354, 5894.145348924468], "value": 4595.504691163003, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.7305077968812, 684.9486288528361, 687.5049964025902, 700.8668353012924, 723.3020135569182, 13774.586762397119, 14320.57038288853, 14668.11991280524, 15168.317425715843, 15345.846541534582, 14665.486707965223, 14754.409823607042, 15498.61253468661, 15656.843431565681, 15818.84181158191, 799.8240387114826, 813.4793732011517, 816.9060558035843, 816.9305609023211, 817.7669364231195, 794.6781553470825, 819.6598411659154, 820.1429506166041, 825.6986200036973, 825.8761185822115, 852.5140669818206, 854.5855260198798, 855.0850589868843, 855.422589751918, 855.5422848775906, 845.9196376344238, 851.8253757979661, 852.0627309959058, 852.0754980845759, 852.1989330029752, 621.710904429438, 624.6252248650818, 624.7188765055697, 624.8875202463555, 625.1341891480332, 14126.434942602284, 14134.787978180335, 14154.221517816515, 14186.0, 15332.386704531806, 14466.638334041629, 14831.406743730238, 15191.620209494851, 15199.772003419957, 15579.532614021484, 11970.162088653798, 12038.518459261619, 12221.450034748368, 12264.448099835528, 12284.754304913851, 3171.619405671311, 3191.266008817962, 3198.8720451181925, 3228.983855080733, 3231.0, 82783.5860820692, 84925.25969090444, 86322.13677863203, 86836.56581717114, 87829.56085219597, 11988.700282492833, 11996.160268781276, 12043.939780301218, 12173.817392739116, 12213.267203967791, 25458.745412546006, 25683.61474577883, 25721.614175787377, 25744.227673169837, 25763.0, 8050.718224862218, 8063.435559510778, 8187.877181842277, 8243.958780206061, 8358.665653373797, 4009.2382447335494, 4062.6546743527133, 4071.8778436646357, 4085.182963407271, 4129.256733787887, 4173.812178451977, 4207.821809893235, 4212.178625168124, 4242.321228603409, 4260.808263628082, 718.2530168624572, 736.4881407421898, 737.7417903733758, 750.4876189344699, 751.6767789850417, 518.3132349636746, 532.5127508329912, 532.8934213157384, 536.2064145065248, 537.159345624097, 9348.626054957862, 9992.10071093605, 10123.1901447884, 10141.290109692398, 10162.339447935861, 516.8914527949173, 518.9792408303625, 536.9543588794996, 537.1834811087126, 541.7941182350681, 4330.523642399334, 4439.7336159829865, 4531.456225252925, 4668.4631267404, 4892.645545464539, 65338.673306633165, 67188.66405667941, 72569.0, 72927.63536182391, 74083.0, 56462.717686411306, 60345.698271508365, 62197.689011554656, 63231.0, 68033.65983170053, 46048.54635902254, 52342.47657523376, 55047.0, 56245.0, 59380.81238375208, 9434.905650943678, 9990.500474976367, 10016.449095299828, 10019.0, 10036.0, 30628.0, 30757.538636920017, 31225.8438707805, 31354.21614459657, 32629.510557342135, 37905.62094379021, 38137.318175048626, 38158.0, 38682.0, 39022.2195556093, 10763.515641796295, 11042.613508527164, 11232.382218977707, 11986.940065299617, 12145.332006739362, 11978.959509983379, 13000.479980800663, 13079.149855259378, 13228.40472178735, 13495.0, 12494.687632809253, 12792.232466052066, 13062.80405793933, 13308.268045257604, 13330.066895317235, 11948.880511195124, 12140.75718485643, 12172.452239649414, 12313.0, 12771.808422873479, 12449.93775031119, 13290.136141150795, 13374.398152083375, 13594.11638243511, 13617.25105119228, 10978.286411383235, 11181.0, 11201.159913006393, 11429.542818287508, 11737.589184378507, 10189.184865210616, 10529.736756581147, 10646.8935310649, 10764.946175269073, 10770.676879693621], "value": 6888.123658796263, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 108.0, 108.0, 107.0, 109.0, 107.0, 108.0, 107.0, 108.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 120.0, 162.0, 119.0, 117.0, 123.0, 117.0, 142.0, 118.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 163.0, 161.0, 164.0, 162.0, 164.0, 162.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 111.0, 74.0, 74.0, 74.0, 75.0, 92.0, 78.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 119.0, 83.0, 83.0, 83.0, 99.0, 84.0, 83.0, 104.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 70.0, 55.0, 55.0, 56.0, 56.0, 56.0, 56.0, 57.0, 57.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 80.0, 79.0, 79.0, 78.0, 78.0, 83.0, 79.0, 87.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 126.0, 125.0, 125.0, 134.0, 129.0, 134.0, 127.0, 169.0, 126.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 183.0, 187.0, 184.0, 189.0, 187.0, 181.0, 186.0, 189.0], "value": 185.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 76.0, 67.0, 64.0, 65.0, 64.0, 66.0], "value": 66.8, "unit": "score"}]}]} 19:34:59 INFO - Return code: 0 19:34:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:34:59 ERROR - # TBPL SUCCESS # 19:34:59 INFO - Running post-action listener: _resource_record_post_action 19:34:59 INFO - [mozharness: 2017-03-21 02:34:59.538783Z] Finished run-tests step (success) 19:34:59 INFO - Running post-run listener: _resource_record_post_run 19:34:59 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 369586176; Read time: 16; Write time: 315916 19:34:59 INFO - TinderboxPrint: CPU usage
12.9% 19:34:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:34:59 INFO - TinderboxPrint: I/O write bytes / time
369,586,176 / 315,916 19:34:59 INFO - TinderboxPrint: CPU idle
8,311.6 (87.1%) 19:34:59 INFO - TinderboxPrint: CPU user
1,188.1 (12.4%) 19:34:59 INFO - TinderboxPrint: Swap in / out
0 / 0 19:34:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111226880; Read time: 0; Write time: 138248 19:34:59 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 257961984; Read time: 0; Write time: 177644 19:35:00 INFO - Running post-run listener: _upload_blobber_files 19:35:00 INFO - Blob upload gear active. 19:35:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:35:00 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 19:35:00 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'] 19:35:00 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 19:35:00 INFO - (blobuploader) - INFO - Open directory for files ... 19:35:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:35:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:35:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:03 INFO - (blobuploader) - INFO - Done attempting. 19:35:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:35:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:35:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:04 INFO - (blobuploader) - INFO - Done attempting. 19:35:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:35:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:35:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:05 INFO - (blobuploader) - INFO - Done attempting. 19:35:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:35:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:35:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:06 INFO - (blobuploader) - INFO - Done attempting. 19:35:06 INFO - (blobuploader) - INFO - Iteration through files over. 19:35:06 INFO - Return code: 0 19:35:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:35:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:35:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bd991446a73aa364047af2c1fa1cd7e6a5016213564a09467f80a52062854c28fc3e3a1ecb94704bbfa726cb53959ae727b175dd519671b635e61ab00a6ba45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8192f41201f5c4104f55e57e1bc73b4a0c8cc82d83bc8fbc34df09e80f3dafd539412812067c9a1bed25ea802912b727221cb848d450b1a6da4a67d151a5cca1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f63d89914272b5b746ed4f25db7ec0d7d371e00f85b5579def9fd4df298c6f33d7524847258aaf2bddac9b73c474f00cf8cbb5bbc38854e34aff5427aaa68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd7766e984f707dd3031f26b90f7339bd583a3ccb1be7f8b778ddbd1bed45caf81c3dd6b5ebd0b37bec4b074ff77c5d616e0e9df0ed5c2f2230f6b4c16a2aab3"} 19:35:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:35:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:35:06 INFO - Contents: 19:35:06 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bd991446a73aa364047af2c1fa1cd7e6a5016213564a09467f80a52062854c28fc3e3a1ecb94704bbfa726cb53959ae727b175dd519671b635e61ab00a6ba45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8192f41201f5c4104f55e57e1bc73b4a0c8cc82d83bc8fbc34df09e80f3dafd539412812067c9a1bed25ea802912b727221cb848d450b1a6da4a67d151a5cca1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f63d89914272b5b746ed4f25db7ec0d7d371e00f85b5579def9fd4df298c6f33d7524847258aaf2bddac9b73c474f00cf8cbb5bbc38854e34aff5427aaa68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd7766e984f707dd3031f26b90f7339bd583a3ccb1be7f8b778ddbd1bed45caf81c3dd6b5ebd0b37bec4b074ff77c5d616e0e9df0ed5c2f2230f6b4c16a2aab3"} 19:35:06 INFO - Running post-run listener: copy_logs_to_upload_dir 19:35:06 INFO - Copying logs to upload dir... 19:35:06 INFO - mkdir: /builds/slave/test/build/upload/logs 19:35:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.184631 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-20 19:35:06.420412) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 19:35:06.424488) ========= 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=db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bd991446a73aa364047af2c1fa1cd7e6a5016213564a09467f80a52062854c28fc3e3a1ecb94704bbfa726cb53959ae727b175dd519671b635e61ab00a6ba45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8192f41201f5c4104f55e57e1bc73b4a0c8cc82d83bc8fbc34df09e80f3dafd539412812067c9a1bed25ea802912b727221cb848d450b1a6da4a67d151a5cca1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f63d89914272b5b746ed4f25db7ec0d7d371e00f85b5579def9fd4df298c6f33d7524847258aaf2bddac9b73c474f00cf8cbb5bbc38854e34aff5427aaa68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd7766e984f707dd3031f26b90f7339bd583a3ccb1be7f8b778ddbd1bed45caf81c3dd6b5ebd0b37bec4b074ff77c5d616e0e9df0ed5c2f2230f6b4c16a2aab3"} build_url:https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/VwmKKWxZQ0KgByCf9vLQ-g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bd991446a73aa364047af2c1fa1cd7e6a5016213564a09467f80a52062854c28fc3e3a1ecb94704bbfa726cb53959ae727b175dd519671b635e61ab00a6ba45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8192f41201f5c4104f55e57e1bc73b4a0c8cc82d83bc8fbc34df09e80f3dafd539412812067c9a1bed25ea802912b727221cb848d450b1a6da4a67d151a5cca1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f63d89914272b5b746ed4f25db7ec0d7d371e00f85b5579def9fd4df298c6f33d7524847258aaf2bddac9b73c474f00cf8cbb5bbc38854e34aff5427aaa68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd7766e984f707dd3031f26b90f7339bd583a3ccb1be7f8b778ddbd1bed45caf81c3dd6b5ebd0b37bec4b074ff77c5d616e0e9df0ed5c2f2230f6b4c16a2aab3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 19:35:06.451286) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:35:06.451775) ========= 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=db0483c52d987ecb441a263b000001ad-1490062449.909500-131772080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:35:06.538864) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 19:35:06.539454) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 19:35:06.540023) ========= ========= Total master_lag: 0.18 =========