builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1494371163.47 results: success (0) revision: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.469372) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.469838) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.470109) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.486084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.486339) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-09 16:06:03-- 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% 37.8M=0s 2017-05-09 16:06:03 (37.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164378 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.684521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.684828) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014542 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.733764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:03.734052) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 --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 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 --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=56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-09 16:06:03,793 truncating revision to first 12 chars 2017-05-09 16:06:03,793 Setting DEBUG logging. 2017-05-09 16:06:03,793 attempt 1/10 2017-05-09 16:06:03,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4b8939ed1281?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-09 16:06:04,424 unpacking tar archive at: mozilla-central-4b8939ed1281/testing/mozharness/ program finished with exit code 0 elapsedTime=0.936293 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:04.689477) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:04.689835) ========= script_repo_revision: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:04.690223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:04.690501) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 16:06:04.701423) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-05-09 16:06:04.701686) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:06:04 INFO - MultiFileLogger online at 20170509 16:06:04 in /builds/slave/test 16:06:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:06:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:06:04 INFO - {'append_to_log': False, 16:06:04 INFO - 'base_work_dir': '/builds/slave/test', 16:06:04 INFO - 'blob_upload_branch': 'Firefox', 16:06:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:06:04 INFO - 'branch': 'Firefox', 16:06:04 INFO - 'buildbot_json_path': 'buildprops.json', 16:06:04 INFO - 'config_files': ('talos/linux_config.py',), 16:06:04 INFO - 'default_actions': ('clobber', 16:06:04 INFO - 'read-buildbot-config', 16:06:04 INFO - 'download-and-extract', 16:06:04 INFO - 'populate-webroot', 16:06:04 INFO - 'create-virtualenv', 16:06:04 INFO - 'install', 16:06:04 INFO - 'run-tests'), 16:06:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:06:04 INFO - 'download_minidump_stackwalk': True, 16:06:04 INFO - 'download_symbols': 'ondemand', 16:06:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:06:04 INFO - 'tooltool.py': '/tools/tooltool.py', 16:06:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:06:04 INFO - '/tools/misc-python/virtualenv.py')}, 16:06:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:06:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:06:04 INFO - 'gecko_profile': False, 16:06:04 INFO - 'gecko_profile_interval': 0, 16:06:04 INFO - 'installer_path': 'installer.exe', 16:06:04 INFO - 'log_level': 'info', 16:06:04 INFO - 'log_name': 'talos', 16:06:04 INFO - 'log_to_console': True, 16:06:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:06:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:06:04 INFO - 'opt_config_files': (), 16:06:04 INFO - 'pip_index': False, 16:06:04 INFO - 'suite': 'dromaeojs-e10s', 16:06:04 INFO - 'system_bits': '32', 16:06:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:06:04 INFO - 'title': 'talos-linux64-ix-037', 16:06:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:06:04 INFO - 'use_talos_json': True, 16:06:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:06:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:06:04 INFO - 'work_dir': 'build'} 16:06:04 INFO - [mozharness: 2017-05-09 23:06:04.929600Z] Running clobber step. 16:06:04 INFO - Running pre-action listener: _resource_record_pre_action 16:06:04 INFO - Running main action method: clobber 16:06:04 INFO - rmtree: /builds/slave/test/build 16:06:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:06:05 INFO - Running post-action listener: _resource_record_post_action 16:06:05 INFO - [mozharness: 2017-05-09 23:06:05.839375Z] Finished clobber step (success) 16:06:05 INFO - [mozharness: 2017-05-09 23:06:05.839474Z] Running read-buildbot-config step. 16:06:05 INFO - Running pre-action listener: _resource_record_pre_action 16:06:05 INFO - Running main action method: read_buildbot_config 16:06:05 INFO - Using buildbot properties: 16:06:05 INFO - { 16:06:05 INFO - "project": "", 16:06:05 INFO - "product": "firefox", 16:06:05 INFO - "who": "asasaki@mozilla.com", 16:06:05 INFO - "installer_path": "public/build/target.tar.bz2", 16:06:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 16:06:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:06:05 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 16:06:05 INFO - "stage_platform": "linux64-stylo", 16:06:05 INFO - "basedir": "/builds/slave/test", 16:06:05 INFO - "buildnumber": 84, 16:06:05 INFO - "platform": "ubuntu64_hw_stylo", 16:06:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:06:05 INFO - "slavebuilddir": "test", 16:06:05 INFO - "taskId": "BoM3-mPqSaeY8Qmmih2PEw", 16:06:05 INFO - "branch": "mozilla-central", 16:06:05 INFO - "script_repo_revision": "production", 16:06:05 INFO - "revision": "4b8939ed128172fcd3433ee25f336a8c1ba77ab0", 16:06:05 INFO - "slavename": "talos-linux64-ix-037", 16:06:05 INFO - "repo_path": "mozilla-central" 16:06:05 INFO - } 16:06:05 INFO - Finding installer, test and symbols from parent task. 16:06:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BoM3-mPqSaeY8Qmmih2PEw'}, attempt #1 16:06:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BoM3-mPqSaeY8Qmmih2PEw'}, attempt #1 16:06:06 INFO - Task dependencies: YZH8xTPhQjKWLEHyL1kmMg 16:06:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg'}, attempt #1 16:06:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2 16:06:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/test_packages.json 16:06:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.crashreporter-symbols.zip 16:06:07 INFO - Running post-action listener: _resource_record_post_action 16:06:07 INFO - [mozharness: 2017-05-09 23:06:07.179966Z] Finished read-buildbot-config step (success) 16:06:07 INFO - [mozharness: 2017-05-09 23:06:07.180184Z] Running download-and-extract step. 16:06:07 INFO - Running pre-action listener: _resource_record_pre_action 16:06:07 INFO - Running main action method: download_and_extract 16:06:07 INFO - mkdir: /builds/slave/test/build/tests 16:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:07 INFO - trying https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.test_packages.json 16:06:07 INFO - Downloading https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:06:07 INFO - Downloaded 986 bytes. 16:06:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:06:07 INFO - Using the following test package requirements: 16:06:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:06:07 INFO - u'common': [u'target.common.tests.zip'], 16:06:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:06:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:06:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:06:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:06:07 INFO - u'mozbase': [u'target.common.tests.zip'], 16:06:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:06:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:06:07 INFO - u'web-platform': [u'target.common.tests.zip', 16:06:07 INFO - u'target.web-platform.tests.zip'], 16:06:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:06:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.common.tests.zip 16:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.common.tests.zip into memory 16:06:08 INFO - Content-Length response header: 58447423 16:06:08 INFO - Bytes received: 58447423 16:06:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:06:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.common.tests.zip 16:06:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:06:19 INFO - Fetch https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.common.tests.zip into memory 16:06:20 INFO - Content-Length response header: 58447423 16:06:20 INFO - Bytes received: 58447423 16:06:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.talos.tests.zip 16:06:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:06:31 INFO - Fetch https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.talos.tests.zip into memory 16:06:32 INFO - Content-Length response header: 14409362 16:06:32 INFO - Bytes received: 14409362 16:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:32 INFO - trying https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2 16:06:32 INFO - Downloading https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:06:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:06:33 INFO - Downloaded 64573572 bytes. 16:06:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2 16:06:33 INFO - mkdir: /builds/slave/test/properties 16:06:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:06:33 INFO - Writing to file /builds/slave/test/properties/build_url 16:06:33 INFO - Contents: 16:06:33 INFO - build_url:https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2 16:06:33 INFO - Running post-action listener: _resource_record_post_action 16:06:33 INFO - Running post-action listener: set_extra_try_arguments 16:06:33 INFO - [mozharness: 2017-05-09 23:06:33.557910Z] Finished download-and-extract step (success) 16:06:33 INFO - [mozharness: 2017-05-09 23:06:33.558007Z] Running populate-webroot step. 16:06:33 INFO - Running pre-action listener: _resource_record_pre_action 16:06:33 INFO - Running main action method: populate_webroot 16:06:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:06:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:06:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:06:33 INFO - '--tppagecycles', 16:06:33 INFO - '7'], 16:06:33 INFO - 'tests': ['tsvgm']}}, 16:06:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:06:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:06:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:06:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:06:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:06:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:06:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['damp', 'tps']}, 16:06:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'tests': ['damp', 'tps']}, 16:06:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['dromaeo_dom']}, 16:06:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:06:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:06:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:06:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['a11yr', 16:06:33 INFO - 'ts_paint', 16:06:33 INFO - 'tpaint', 16:06:33 INFO - 'sessionrestore', 16:06:33 INFO - 'sessionrestore_no_auto_restore', 16:06:33 INFO - 'tabpaint']}, 16:06:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:06:33 INFO - 'ts_paint', 16:06:33 INFO - 'tpaint', 16:06:33 INFO - 'sessionrestore', 16:06:33 INFO - 'sessionrestore_no_auto_restore', 16:06:33 INFO - 'tabpaint']}, 16:06:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:06:33 INFO - 'ts_paint', 16:06:33 INFO - 'tpaint', 16:06:33 INFO - 'sessionrestore', 16:06:33 INFO - 'sessionrestore_no_auto_restore', 16:06:33 INFO - 'tabpaint']}, 16:06:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:06:33 INFO - 'ts_paint', 16:06:33 INFO - 'tpaint', 16:06:33 INFO - 'sessionrestore', 16:06:33 INFO - 'sessionrestore_no_auto_restore', 16:06:33 INFO - 'tabpaint']}, 16:06:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['a11yr', 16:06:33 INFO - 'ts_paint', 16:06:33 INFO - 'tpaint', 16:06:33 INFO - 'sessionrestore', 16:06:33 INFO - 'sessionrestore_no_auto_restore', 16:06:33 INFO - 'tabpaint']}, 16:06:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['a11yr', 16:06:33 INFO - 'ts_paint', 16:06:33 INFO - 'tpaint', 16:06:33 INFO - 'sessionrestore', 16:06:33 INFO - 'sessionrestore_no_auto_restore', 16:06:33 INFO - 'tabpaint']}, 16:06:33 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:06:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:06:33 INFO - 'bloom_basic_ref']}, 16:06:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['tsvgx', 16:06:33 INFO - 'tsvgr_opacity', 16:06:33 INFO - 'tart', 16:06:33 INFO - 'tscrollx', 16:06:33 INFO - 'cart', 16:06:33 INFO - 'tsvg_static']}, 16:06:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:06:33 INFO - 'tsvgr_opacity', 16:06:33 INFO - 'tart', 16:06:33 INFO - 'tscrollx', 16:06:33 INFO - 'cart', 16:06:33 INFO - 'tsvg_static']}, 16:06:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'talos_options': ['--disable-e10s'], 16:06:33 INFO - 'tests': ['tp5o']}, 16:06:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:06:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'talos_options': ['--disable-e10s', 16:06:33 INFO - '--xperf_path', 16:06:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:06:33 INFO - 'tests': ['tp5n']}, 16:06:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:06:33 INFO - 'talos_options': ['--xperf_path', 16:06:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:06:33 INFO - 'tests': ['tp5n']}}, 16:06:33 INFO - 'talos.zip': {'path': '', 16:06:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:06:33 INFO - Running post-action listener: _resource_record_post_action 16:06:33 INFO - [mozharness: 2017-05-09 23:06:33.571528Z] Finished populate-webroot step (success) 16:06:33 INFO - [mozharness: 2017-05-09 23:06:33.571616Z] Running create-virtualenv step. 16:06:33 INFO - Running pre-action listener: _resource_record_pre_action 16:06:33 INFO - Running main action method: create_virtualenv 16:06:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:06:33 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 16:06:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:06:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:06:33 INFO - Using real prefix '/usr' 16:06:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:06:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:06:36 INFO - Installing pip.................done. 16:06:36 INFO - Return code: 0 16:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:06:36 INFO - Reading from file tmpfile_stdout 16:06:36 INFO - Output received: 16:06:36 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) 16:06:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:36 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:36 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 16:06:36 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 16:06:36 INFO - Using env: {'DISPLAY': ':0', 16:06:36 INFO - 'HOME': '/home/cltbld', 16:06:36 INFO - 'LANG': 'en_US.UTF-8', 16:06:36 INFO - 'LANGUAGE': 'en_US:en', 16:06:36 INFO - 'LOGNAME': 'cltbld', 16:06:36 INFO - 'MAIL': '/var/mail/cltbld', 16:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:36 INFO - 'MOZ_NO_REMOTE': '1', 16:06:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:36 INFO - 'NO_EM_RESTART': '1', 16:06:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:36 INFO - 'PWD': '/builds/slave/test', 16:06:36 INFO - 'SHELL': '/bin/bash', 16:06:36 INFO - 'SHLVL': '1', 16:06:36 INFO - 'TERM': 'linux', 16:06:36 INFO - 'TMOUT': '86400', 16:06:36 INFO - 'USER': 'cltbld', 16:06:36 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:36 INFO - '_': '/tools/buildbot/bin/python'} 16:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:36 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 16:06:36 INFO - Cleaning up... 16:06:36 INFO - Return code: 0 16:06:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:36 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:36 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 16:06:36 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 16:06:36 INFO - Using env: {'DISPLAY': ':0', 16:06:36 INFO - 'HOME': '/home/cltbld', 16:06:36 INFO - 'LANG': 'en_US.UTF-8', 16:06:36 INFO - 'LANGUAGE': 'en_US:en', 16:06:36 INFO - 'LOGNAME': 'cltbld', 16:06:36 INFO - 'MAIL': '/var/mail/cltbld', 16:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:36 INFO - 'MOZ_NO_REMOTE': '1', 16:06:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:36 INFO - 'NO_EM_RESTART': '1', 16:06:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:36 INFO - 'PWD': '/builds/slave/test', 16:06:36 INFO - 'SHELL': '/bin/bash', 16:06:36 INFO - 'SHLVL': '1', 16:06:36 INFO - 'TERM': 'linux', 16:06:36 INFO - 'TMOUT': '86400', 16:06:36 INFO - 'USER': 'cltbld', 16:06:36 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:36 INFO - '_': '/tools/buildbot/bin/python'} 16:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:37 INFO - Downloading/unpacking psutil>=3.1.1 16:06:37 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 16:06:37 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 16:06:37 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 16:06:37 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 16:06:37 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 16:06:37 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 16:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:06:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:38 INFO - Installing collected packages: psutil 16:06:38 INFO - Running setup.py install for psutil 16:06:39 INFO - building 'psutil._psutil_linux' extension 16:06:39 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 16:06:42 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 16:06:42 INFO - building 'psutil._psutil_posix' extension 16:06:42 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 16:06:42 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 16:06:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:42 INFO - Successfully installed psutil 16:06:42 INFO - Cleaning up... 16:06:42 INFO - Return code: 0 16:06:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:42 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:42 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 16:06:42 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 16:06:42 INFO - Using env: {'DISPLAY': ':0', 16:06:42 INFO - 'HOME': '/home/cltbld', 16:06:42 INFO - 'LANG': 'en_US.UTF-8', 16:06:42 INFO - 'LANGUAGE': 'en_US:en', 16:06:42 INFO - 'LOGNAME': 'cltbld', 16:06:42 INFO - 'MAIL': '/var/mail/cltbld', 16:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:42 INFO - 'MOZ_NO_REMOTE': '1', 16:06:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:42 INFO - 'NO_EM_RESTART': '1', 16:06:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:42 INFO - 'PWD': '/builds/slave/test', 16:06:42 INFO - 'SHELL': '/bin/bash', 16:06:42 INFO - 'SHLVL': '1', 16:06:42 INFO - 'TERM': 'linux', 16:06:42 INFO - 'TMOUT': '86400', 16:06:42 INFO - 'USER': 'cltbld', 16:06:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:42 INFO - '_': '/tools/buildbot/bin/python'} 16:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:06:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:06:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:06:44 INFO - Installing collected packages: mozsystemmonitor 16:06:44 INFO - Running setup.py install for mozsystemmonitor 16:06:44 INFO - Successfully installed mozsystemmonitor 16:06:44 INFO - Cleaning up... 16:06:44 INFO - Return code: 0 16:06:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:44 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:44 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 16:06:44 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 16:06:44 INFO - Using env: {'DISPLAY': ':0', 16:06:44 INFO - 'HOME': '/home/cltbld', 16:06:44 INFO - 'LANG': 'en_US.UTF-8', 16:06:44 INFO - 'LANGUAGE': 'en_US:en', 16:06:44 INFO - 'LOGNAME': 'cltbld', 16:06:44 INFO - 'MAIL': '/var/mail/cltbld', 16:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:44 INFO - 'MOZ_NO_REMOTE': '1', 16:06:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:44 INFO - 'NO_EM_RESTART': '1', 16:06:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:44 INFO - 'PWD': '/builds/slave/test', 16:06:44 INFO - 'SHELL': '/bin/bash', 16:06:44 INFO - 'SHLVL': '1', 16:06:44 INFO - 'TERM': 'linux', 16:06:44 INFO - 'TMOUT': '86400', 16:06:44 INFO - 'USER': 'cltbld', 16:06:44 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:44 INFO - '_': '/tools/buildbot/bin/python'} 16:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:44 INFO - Downloading/unpacking jsonschema==2.5.1 16:06: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 16:06: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 16:06: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 16:06: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 16:06: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 16:06: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 16:06:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:06:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:06:45 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 16:06:45 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 16:06:45 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 16:06:45 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 16:06:45 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 16:06:45 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 16:06:45 INFO - Downloading functools32-3.2.3-2.tar.gz 16:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:06:45 INFO - warning: no files found matching '*.txt' 16:06:45 INFO - no previously-included directories found matching 'build' 16:06:45 INFO - no previously-included directories found matching 'dist' 16:06:45 INFO - no previously-included directories found matching '.git*' 16:06:45 INFO - Installing collected packages: jsonschema, functools32 16:06:45 INFO - Running setup.py install for functools32 16:06:45 INFO - warning: no files found matching '*.txt' 16:06:45 INFO - no previously-included directories found matching 'build' 16:06:45 INFO - no previously-included directories found matching 'dist' 16:06:45 INFO - no previously-included directories found matching '.git*' 16:06:45 INFO - Successfully installed jsonschema functools32 16:06:45 INFO - Cleaning up... 16:06:45 INFO - Return code: 0 16:06:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:06:45 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')]} 16:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:45 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')]} 16:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:45 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:45 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 16:06:45 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 16:06:45 INFO - Using env: {'DISPLAY': ':0', 16:06:45 INFO - 'HOME': '/home/cltbld', 16:06:45 INFO - 'LANG': 'en_US.UTF-8', 16:06:45 INFO - 'LANGUAGE': 'en_US:en', 16:06:45 INFO - 'LOGNAME': 'cltbld', 16:06:45 INFO - 'MAIL': '/var/mail/cltbld', 16:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:45 INFO - 'MOZ_NO_REMOTE': '1', 16:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:45 INFO - 'NO_EM_RESTART': '1', 16:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:45 INFO - 'PWD': '/builds/slave/test', 16:06:45 INFO - 'SHELL': '/bin/bash', 16:06:45 INFO - 'SHLVL': '1', 16:06:45 INFO - 'TERM': 'linux', 16:06:45 INFO - 'TMOUT': '86400', 16:06:45 INFO - 'USER': 'cltbld', 16:06:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:45 INFO - '_': '/tools/buildbot/bin/python'} 16:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:06:46 INFO - Cleaning up... 16:06:46 INFO - Return code: 0 16:06:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:06: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')]} 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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', '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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:06: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 blobuploader==1.2.4 16:06:46 INFO - Using env: {'DISPLAY': ':0', 16:06:46 INFO - 'HOME': '/home/cltbld', 16:06:46 INFO - 'LANG': 'en_US.UTF-8', 16:06:46 INFO - 'LANGUAGE': 'en_US:en', 16:06:46 INFO - 'LOGNAME': 'cltbld', 16:06:46 INFO - 'MAIL': '/var/mail/cltbld', 16:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:46 INFO - 'MOZ_NO_REMOTE': '1', 16:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:46 INFO - 'NO_EM_RESTART': '1', 16:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:46 INFO - 'PWD': '/builds/slave/test', 16:06:46 INFO - 'SHELL': '/bin/bash', 16:06:46 INFO - 'SHLVL': '1', 16:06:46 INFO - 'TERM': 'linux', 16:06:46 INFO - 'TMOUT': '86400', 16:06:46 INFO - 'USER': 'cltbld', 16:06:46 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:46 INFO - '_': '/tools/buildbot/bin/python'} 16:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:46 INFO - Downloading/unpacking blobuploader==1.2.4 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:47 INFO - Downloading blobuploader-1.2.4.tar.gz 16:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:06:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:06: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 16:06: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 16:06: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 16:06: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 16:06: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 16:06: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 16:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:06:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:06: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 16:06: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 16:06: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 16:06: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 16:06: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 16:06: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 16:06:48 INFO - Downloading docopt-0.6.1.tar.gz 16:06:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:06:48 INFO - Installing collected packages: blobuploader, requests, docopt 16:06:48 INFO - Running setup.py install for blobuploader 16:06:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:06:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:06:48 INFO - Running setup.py install for requests 16:06:48 INFO - Running setup.py install for docopt 16:06:48 INFO - Successfully installed blobuploader requests docopt 16:06:48 INFO - Cleaning up... 16:06:48 INFO - Return code: 0 16:06:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:48 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:48 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 16:06:48 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 16:06:48 INFO - Using env: {'DISPLAY': ':0', 16:06:48 INFO - 'HOME': '/home/cltbld', 16:06:48 INFO - 'LANG': 'en_US.UTF-8', 16:06:48 INFO - 'LANGUAGE': 'en_US:en', 16:06:48 INFO - 'LOGNAME': 'cltbld', 16:06:48 INFO - 'MAIL': '/var/mail/cltbld', 16:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:48 INFO - 'MOZ_NO_REMOTE': '1', 16:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:48 INFO - 'NO_EM_RESTART': '1', 16:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:48 INFO - 'PWD': '/builds/slave/test', 16:06:48 INFO - 'SHELL': '/bin/bash', 16:06:48 INFO - 'SHLVL': '1', 16:06:48 INFO - 'TERM': 'linux', 16:06:48 INFO - 'TMOUT': '86400', 16:06:48 INFO - 'USER': 'cltbld', 16:06:48 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:48 INFO - '_': '/tools/buildbot/bin/python'} 16:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:48 INFO - Running setup.py (path:/tmp/pip-ZF7at_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:48 INFO - Running setup.py (path:/tmp/pip-Km5Z6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:49 INFO - Running setup.py (path:/tmp/pip-yF28lE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:49 INFO - Running setup.py (path:/tmp/pip-bxgGRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:49 INFO - Running setup.py (path:/tmp/pip-8mO70c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:49 INFO - Running setup.py (path:/tmp/pip-tsP2Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:49 INFO - Running setup.py (path:/tmp/pip-c9eAE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:49 INFO - Running setup.py (path:/tmp/pip-B9TxJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:49 INFO - Running setup.py (path:/tmp/pip-05fGvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:49 INFO - Running setup.py (path:/tmp/pip-kj9oYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:49 INFO - Running setup.py (path:/tmp/pip-179CL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:49 INFO - Running setup.py (path:/tmp/pip-kLLz2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:49 INFO - Running setup.py (path:/tmp/pip-TmlrlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:49 INFO - Running setup.py (path:/tmp/pip-QjFuGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:49 INFO - Running setup.py (path:/tmp/pip-woZQao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:49 INFO - Running setup.py (path:/tmp/pip-h8joiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:50 INFO - Running setup.py (path:/tmp/pip-zNoAJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:06:50 INFO - Running setup.py install for manifestparser 16:06:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozcrash 16:06:50 INFO - Running setup.py install for mozdebug 16:06:50 INFO - Running setup.py install for mozdevice 16:06:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozfile 16:06:50 INFO - Running setup.py install for mozhttpd 16:06:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozinfo 16:06:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozInstall 16:06:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozleak 16:06:50 INFO - Running setup.py install for mozlog 16:06:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Running setup.py install for moznetwork 16:06:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Running setup.py install for mozprocess 16:06:51 INFO - Running setup.py install for mozprofile 16:06:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Running setup.py install for mozrunner 16:06:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Running setup.py install for mozscreenshot 16:06:51 INFO - Running setup.py install for moztest 16:06:51 INFO - Running setup.py install for mozversion 16:06:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:06:51 INFO - Cleaning up... 16:06:51 INFO - Return code: 0 16:06:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06: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')]} 16:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:51 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:51 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 16:06:51 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 16:06:51 INFO - Using env: {'DISPLAY': ':0', 16:06:51 INFO - 'HOME': '/home/cltbld', 16:06:51 INFO - 'LANG': 'en_US.UTF-8', 16:06:51 INFO - 'LANGUAGE': 'en_US:en', 16:06:51 INFO - 'LOGNAME': 'cltbld', 16:06:51 INFO - 'MAIL': '/var/mail/cltbld', 16:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:51 INFO - 'MOZ_NO_REMOTE': '1', 16:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:51 INFO - 'NO_EM_RESTART': '1', 16:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:51 INFO - 'PWD': '/builds/slave/test', 16:06:51 INFO - 'SHELL': '/bin/bash', 16:06:51 INFO - 'SHLVL': '1', 16:06:51 INFO - 'TERM': 'linux', 16:06:51 INFO - 'TMOUT': '86400', 16:06:51 INFO - 'USER': 'cltbld', 16:06:51 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:51 INFO - '_': '/tools/buildbot/bin/python'} 16:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:52 INFO - Running setup.py (path:/tmp/pip-VnJ0Bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:52 INFO - Running setup.py (path:/tmp/pip-MSl3tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:52 INFO - Running setup.py (path:/tmp/pip-ES7_sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:52 INFO - Running setup.py (path:/tmp/pip-W6onhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:52 INFO - Running setup.py (path:/tmp/pip-FJfyXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:52 INFO - Running setup.py (path:/tmp/pip-b6qm95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:52 INFO - Running setup.py (path:/tmp/pip-J8AJIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:52 INFO - Running setup.py (path:/tmp/pip-K5gaaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:52 INFO - Running setup.py (path:/tmp/pip-oTxACN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:52 INFO - Running setup.py (path:/tmp/pip-IKcjjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:52 INFO - Running setup.py (path:/tmp/pip-UQ5WgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:52 INFO - Running setup.py (path:/tmp/pip-a2aECI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:52 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)) 16:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:52 INFO - Running setup.py (path:/tmp/pip-JcBpwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:53 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)) 16:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:53 INFO - Running setup.py (path:/tmp/pip-dYAhi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:53 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)) 16:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:53 INFO - Running setup.py (path:/tmp/pip-LRKOzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:53 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)) 16:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:53 INFO - Running setup.py (path:/tmp/pip-gR6lBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:53 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)) 16:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:53 INFO - Running setup.py (path:/tmp/pip-PVKAaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:53 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)) 16:06:53 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)) 16:06:53 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)) 16:06:53 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)) 16:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:06:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:06:53 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 16:06:53 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 16:06:53 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 16:06:53 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 16:06:53 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 16:06:53 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 16:06:54 INFO - Downloading blessings-1.6.tar.gz 16:06:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:06:54 INFO - Installing collected packages: blessings 16:06:54 INFO - Running setup.py install for blessings 16:06:54 INFO - Successfully installed blessings 16:06:54 INFO - Cleaning up... 16:06:54 INFO - Return code: 0 16:06:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:54 INFO - Reading from file tmpfile_stdout 16:06:54 INFO - Current package versions: 16:06:54 INFO - argparse == 1.2.1 16:06:54 INFO - blessings == 1.6 16:06:54 INFO - blobuploader == 1.2.4 16:06:54 INFO - docopt == 0.6.1 16:06:54 INFO - functools32 == 3.2.3-2 16:06:54 INFO - jsonschema == 2.5.1 16:06:54 INFO - manifestparser == 1.1 16:06:54 INFO - mozInstall == 1.12 16:06:54 INFO - mozcrash == 1.0 16:06:54 INFO - mozdebug == 0.1 16:06:54 INFO - mozdevice == 0.50 16:06:54 INFO - mozfile == 1.2 16:06:54 INFO - mozhttpd == 0.7 16:06:54 INFO - mozinfo == 0.9 16:06:54 INFO - mozleak == 0.1 16:06:54 INFO - mozlog == 3.4 16:06:54 INFO - moznetwork == 0.27 16:06:54 INFO - mozprocess == 0.25 16:06:54 INFO - mozprofile == 0.28 16:06:54 INFO - mozrunner == 6.13 16:06:54 INFO - mozscreenshot == 0.1 16:06:54 INFO - mozsystemmonitor == 0.3 16:06:54 INFO - moztest == 0.8 16:06:54 INFO - mozversion == 1.4 16:06:54 INFO - psutil == 3.1.1 16:06:54 INFO - requests == 1.2.3 16:06:54 INFO - wsgiref == 0.1.2 16:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:54 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')]} 16:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:54 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')]} 16:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:54 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:54 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 16:06:54 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 16:06:54 INFO - Using env: {'DISPLAY': ':0', 16:06:54 INFO - 'HOME': '/home/cltbld', 16:06:54 INFO - 'LANG': 'en_US.UTF-8', 16:06:54 INFO - 'LANGUAGE': 'en_US:en', 16:06:54 INFO - 'LOGNAME': 'cltbld', 16:06:54 INFO - 'MAIL': '/var/mail/cltbld', 16:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:54 INFO - 'MOZ_NO_REMOTE': '1', 16:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:54 INFO - 'NO_EM_RESTART': '1', 16:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:54 INFO - 'PWD': '/builds/slave/test', 16:06:54 INFO - 'SHELL': '/bin/bash', 16:06:54 INFO - 'SHLVL': '1', 16:06:54 INFO - 'TERM': 'linux', 16:06:54 INFO - 'TMOUT': '86400', 16:06:54 INFO - 'USER': 'cltbld', 16:06:54 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:54 INFO - '_': '/tools/buildbot/bin/python'} 16:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 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)) 16:06:55 INFO - Cleaning up... 16:06:55 INFO - Return code: 0 16:06:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:06: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')]} 16:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:55 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 0x207d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2249a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', '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 16:06:55 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 16:06:55 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 16:06:55 INFO - Using env: {'DISPLAY': ':0', 16:06:55 INFO - 'HOME': '/home/cltbld', 16:06:55 INFO - 'LANG': 'en_US.UTF-8', 16:06:55 INFO - 'LANGUAGE': 'en_US:en', 16:06:55 INFO - 'LOGNAME': 'cltbld', 16:06:55 INFO - 'MAIL': '/var/mail/cltbld', 16:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:55 INFO - 'MOZ_NO_REMOTE': '1', 16:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:55 INFO - 'NO_EM_RESTART': '1', 16:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:55 INFO - 'PWD': '/builds/slave/test', 16:06:55 INFO - 'SHELL': '/bin/bash', 16:06:55 INFO - 'SHLVL': '1', 16:06:55 INFO - 'TERM': 'linux', 16:06:55 INFO - 'TMOUT': '86400', 16:06:55 INFO - 'USER': 'cltbld', 16:06:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:55 INFO - '_': '/tools/buildbot/bin/python'} 16:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:06:55 INFO - Cleaning up... 16:06:55 INFO - Return code: 0 16:06:55 INFO - Running post-action listener: _resource_record_post_action 16:06:55 INFO - Running post-action listener: _start_resource_monitoring 16:06:55 INFO - Starting resource monitoring. 16:06:55 INFO - [mozharness: 2017-05-09 23:06:55.402056Z] Finished create-virtualenv step (success) 16:06:55 INFO - [mozharness: 2017-05-09 23:06:55.403143Z] Running install step. 16:06:55 INFO - Running pre-action listener: _resource_record_pre_action 16:06:55 INFO - Running main action method: install 16:06:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:55 INFO - Reading from file tmpfile_stdout 16:06:55 INFO - Detecting whether we're running mozinstall >=1.0... 16:06:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:06:55 INFO - Reading from file tmpfile_stdout 16:06:55 INFO - Output received: 16:06:55 INFO - Usage: mozinstall [options] installer 16:06:55 INFO - Options: 16:06:55 INFO - -h, --help show this help message and exit 16:06:55 INFO - -d DEST, --destination=DEST 16:06:55 INFO - Directory to install application into. [default: 16:06:55 INFO - "/builds/slave/test"] 16:06:55 INFO - --app=APP Application being installed. [default: firefox] 16:06:55 INFO - mkdir: /builds/slave/test/build/application 16:06:55 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'] 16:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:07:11 INFO - Reading from file tmpfile_stdout 16:07:11 INFO - Output received: 16:07:11 INFO - /builds/slave/test/build/application/firefox/firefox 16:07:11 INFO - Running post-action listener: _resource_record_post_action 16:07:11 INFO - [mozharness: 2017-05-09 23:07:11.907559Z] Finished install step (success) 16:07:11 INFO - [mozharness: 2017-05-09 23:07:11.907841Z] Running run-tests step. 16:07:11 INFO - Running pre-action listener: _resource_record_pre_action 16:07:11 INFO - Running main action method: run_tests 16:07:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:07:11 INFO - Python 2.7.3 16:07:11 INFO - Return code: 0 16:07:11 INFO - grabbing minidump binary from tooltool 16:07:11 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')]} 16:07:11 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 0x23eca30>, '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 0x23ed7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23edca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:07:11 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 16:07:11 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 16:07:11 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 16:07:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:07:12 INFO - Return code: 0 16:07:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:07:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:07:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:07:12 INFO - ENV: RUST_BACKTRACE is now 1 16:07:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:07:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:07:12 INFO - Using env: {'DISPLAY': ':0', 16:07:12 INFO - 'HOME': '/home/cltbld', 16:07:12 INFO - 'LANG': 'en_US.UTF-8', 16:07:12 INFO - 'LANGUAGE': 'en_US:en', 16:07:12 INFO - 'LOGNAME': 'cltbld', 16:07:12 INFO - 'MAIL': '/var/mail/cltbld', 16:07:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:07:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:12 INFO - 'MOZ_NO_REMOTE': '1', 16:07:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:12 INFO - 'NO_EM_RESTART': '1', 16:07:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:12 INFO - 'PWD': '/builds/slave/test', 16:07:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:07:12 INFO - 'RUST_BACKTRACE': '1', 16:07:12 INFO - 'SHELL': '/bin/bash', 16:07:12 INFO - 'SHLVL': '1', 16:07:12 INFO - 'TERM': 'linux', 16:07:12 INFO - 'TMOUT': '86400', 16:07:12 INFO - 'USER': 'cltbld', 16:07:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972', 16:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:12 INFO - '_': '/tools/buildbot/bin/python'} 16:07:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:07:12 INFO - mozversion application_buildid: 20170509202905 16:07:12 INFO - mozversion application_changeset: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 16:07:12 INFO - mozversion application_display_name: Nightly 16:07:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:07:12 INFO - mozversion application_name: Firefox 16:07:12 INFO - mozversion application_remotingname: firefox 16:07:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:07:12 INFO - mozversion application_vendor: Mozilla 16:07:12 INFO - mozversion application_version: 55.0a1 16:07:12 INFO - mozversion platform_buildid: 20170509202905 16:07:12 INFO - mozversion platform_changeset: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 16:07:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:07:12 INFO - mozversion platform_version: 55.0a1 16:07:12 INFO - using testdate: 1494371232 16:07:12 INFO - actual date: 1494371232 16:07:12 INFO - starting webserver on 'localhost:58517' 16:07:12 INFO - SUITE-START | Running 2 tests 16:07:12 INFO - TEST-START | dromaeo_css 16:07:12 INFO - operating with platform_type : linux_ 16:07:12 INFO - Initialising browser for dromaeo_css test... 16:07:12 INFO - TEST-INFO | started process 3324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGyUzgV/profile http://localhost:58517/getInfo.html) 16:07:16 INFO - PID 3324 | __metrics Screen width/height:1600/1200 16:07:16 INFO - PID 3324 | colorDepth:24 16:07:16 INFO - PID 3324 | Browser inner width/height: 1024/695 16:07:16 INFO - PID 3324 | __metrics 16:07:20 INFO - TEST-INFO | 3324: exit 0 16:07:20 INFO - Browser initialized. 16:07:20 INFO - Running cycle 1/1 for dromaeo_css test... 16:07:20 INFO - TEST-INFO | started process 3519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGyUzgV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:31 INFO - PID 3519 | RSS: Main: 168652800 16:07:31 INFO - PID 3519 | 16:10:37 INFO - PID 3519 | Cycle 1(1): loaded http://localhost:58517/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58517/tests/dromaeo/cssquery-ext.html) 16:10:39 INFO - PID 3519 | RSS: Main: 182833152 16:10:39 INFO - PID 3519 | 16:13:41 INFO - PID 3519 | Cycle 1(1): loaded http://localhost:58517/tests/dromaeo/cssquery-ext.html (next: http://localhost:58517/tests/dromaeo/cssquery-jquery.html) 16:13:41 INFO - PID 3519 | RSS: Main: 180080640 16:13:41 INFO - PID 3519 | 16:16:43 INFO - PID 3519 | Cycle 1(1): loaded http://localhost:58517/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58517/tests/dromaeo/cssquery-mootools.html) 16:16:44 INFO - PID 3519 | RSS: Main: 191770624 16:16:44 INFO - PID 3519 | 16:19:45 INFO - PID 3519 | Cycle 1(1): loaded http://localhost:58517/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58517/tests/dromaeo/cssquery-prototype.html) 16:19:46 INFO - PID 3519 | RSS: Main: 181719040 16:19:46 INFO - PID 3519 | 16:22:49 INFO - PID 3519 | Cycle 1(1): loaded http://localhost:58517/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58517/tests/dromaeo/cssquery-yui.html) 16:22:50 INFO - PID 3519 | RSS: Main: 189505536 16:22:50 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | Cycle 1(1): loaded http://localhost:58517/tests/dromaeo/cssquery-yui.html (next: http://localhost:58517/tests/dromaeo/cssquery-dojo.html) 16:25:52 INFO - PID 3519 | RSS: Main: 191791104 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | __start_tp_report 16:25:52 INFO - PID 3519 | _x_x_mozilla_page_load 16:25:52 INFO - PID 3519 | _x_x_mozilla_page_load_details 16:25:52 INFO - PID 3519 | |i|pagename|runs| 16:25:52 INFO - PID 3519 | |0;dojo.html;2724.114662734611;2770.5567109262497;2796.063318788207;2811.226912599034;2811.6766571844246;5222.8694282642955;5308.867278318044;5326.813561525348;5369.4362091980365;5382.650127741694;5284.128118860383;5290.603204759649;5292.206169074631;5357;5373.543248823855;4717.174494463472;4728.4798672146035;4743.051389722053;4754.263089221176;4803.039392121572;4790.113828941657;4810.350602668647;4829.22732362822;4837.540433658797;4859.465458799529;4599.44806623204;4652.092841895832;4663.370444989951;4725.976370118162;4758.333833263346;4691.437027556681;4692.343071969927;4698.483166851644;4739.763011849428;4751.31105989633;4649.511801260886;4669.929951050737;4676.415448068991;4692.906141877143;4714.269288260325;5075.923861142086;5116.437191908924;5173.948260517422;5181.81863634771;5190.922136167923;5744.454276843693;5772.942270577324;5792.044312688401;5803.419658034163;5837.09525023622;5804.680742559158;5805.448482394166;5808.244928159313;5816.505597024258;5818.970905145447;5167.147420675622;5236.73816309183;5389.622726409114;5390.703511306916;5394.568434525268;5772.913406298909;5838.2702162229725;5859.20900678403;5894.852628684317;5902.704864756745;5598.636088654224;5658.519025882763;5661.46216109469;5714.657120572779;5737.913931291034;5751.971240143856;5759;5780.219670344533;5780.6531608102805;5802.709864506758;5709.9714501427225;5713.771449142071;5714.79998200061;5729.88540229193;5731.570132240098;5710;5734.512566431817;5738.483536481736;5743.569232307594;5747.971260143756;4655.208614535537;4724.503927087675;4724.936889199903;4730.195866702668;4732.100900828831;4059.4722686050623;4060.0864805418546;4082.7550346978683;4188.790560471964;4190.664746820247;4968.726720030433;4975.577075948585;4990.126727822617;5001.874953126201;5016.44819069902;5794.565407594448;5802.709864506758;5809.3609702932645;5812.447817457335;5819.82540523785;4874.19575770001;4949;4949.802007919643;4958.305837182797;4975.328330675313;4978.079055374767;5055.595552355802;5063.594912406998;5067.518585734423;5072.289879416885;5350;5350.5987050971335;5355.946440535545;5357.276767636319;5358.410574836843;5649.406812284651;5754.079347304411;5760.308762948473;5763.452472015152;5768.336641286306;5307.336582927134;5327.653702509325;5342.091844386386;5353.330833645794;5371.435999220025;5601.943980560142;5650.237217975685;5654.953833540808;5691.373948865705;5700;5707.543396528351;5709.800156994485;5714.314282286152;5738.139279108167;5796.913046304225;5569.777208911761;5570;5576.525995290283;5578.553715702815;5581.330240371181;5513.9448605513435;5560.138178582326;5602.803901863415;5605.635633683797;5619.353774316006;5558.666480011212;5568.554515638658;5570.582206334622;5578.135389014709;5580.972095139498;5685.289338832646;5691.089425691869;5699.230603868425;5703.231998080609;5703.287089113861;5361.865953351042;5428;5434.75543600531;5503.229547863222;5510.614257001971 16:25:52 INFO - PID 3519 | |1;ext.html;576.2537513919474;619.108483783352;640.8397900524869;1183.8046722290817;1206.1496644865372;34165.316693666115;34954.30091398171;35036.474452883194;35050.474242886376;35099.473507897404;32579.348413031727;33234.34719139322;33234.66765332346;33301.83349083251;33321.66678333216;6821.420179284767;6931.16825980883;6940.4794640401915;6940.826479338007;6951.582905025714;6863.588184708909;6929.5842249465195;6934.687939042755;6945.47908906834;6972.372486476216;6913.7925862223965;6933.68798404073;6947.305269473038;6966.547174433671;6982.650867456632;6888.655567221619;6938.826529336781;6954.687039083254;6975.895361569581;6984.615846128461;6806.353396427331;6822.863542729117;6854.074699915497;6854.691538880713;6866.279040700754;31447.84276078628;31975;32078.358432831214;32303.6769632303;32360.86736964195;3047.405755877605;3088.058142266608;3091.7835751497405;3091.7990330628436;3092.4588197065423;24276.028958841624;24322;24352.26943191707;24424.755752442426;24578.139765108313;2977.2556860784803;2985.208919636298;3003.6245469316336;3008.1877892968996;3019.6829332919947;149041;149118;149279.25360373128;149694;155957.22021389933;40807.59192408038;41092.38361424581;41093;41097.794511027256;41149;42275.78862105731;42549;44583.777081114386;45274;45500;12634.684132896751;12754.107212495037;12778;12784.74430511403;12794.42425090873;9921.40471571708;9988.450635215133;9997.250206234416;10018.39889606626;10048.69853904384;12972.221666700028;13011;13060.804087938688;13098.345082745825;13209.141405808592;5434.163594400986;5534.916976245359;5560.805371811967;5561.694106824163;5595.552355811526;880.4101252161067;914.679862048291;931.0549791961165;943.2878176976338;952.1335584617964;20781.688274675893;21165.89417052905;21465.67801482979;21745.456363591038;22076.779232207795;985.9507024648739;1014.8731408573929;1019.2508506247901;1027.2655051638114;1028.3469996552094;11010.614628487965;11355.602553910574;11371.204015718986;11405.372704501333;11536.365499897585;167409;168493;169480;169566;171651;40642.59357406447;41049.38425923613;41198.588014120076;41212.58787412148;41257;1198.9100817438728;1265.8797414245632;1269.9365031748375;1272.5609664665676;1277.9552715655093;19673.409797706092;19761.703574446106;20491.69262461094;21085.683714743984;21234.469138271048;29635;29743.40513189681;29780.70219297779;29809.25476863096;29852.70147298499;140763;141655;141897;142475.28762356122;142740;28607.13678674672;28842.27894302659;28844.567331489623;29024.274393140342;29116.854415727787;35830;35894.461583076794;36045.63954360528;36077.27845443129;36180;25889.309436526615;27247.182584522496;27648.861755691094;27916.441671166056;27947.301317467227;27105.1868443947;27214.319642009108;27713.86143069272;28703.138905832857;28736.137915862557;29511;29702.702972969993;29912.842298410455;30197.547036794025;30544.389112218076;26393.60409593896;26495;26500.867495662398;26907.327316817235;27912;21019.264325748525;21774.541344661975;21861;21975.23086691958;22133.33599992003 16:25:52 INFO - PID 3519 | |2;jquery.html;417.25936462778543;436.37598234524626;439.498971172863;441.06274167394287;448.1977260703341;16910.154492275382;17182.91408542957;17844.732329015074;17877.463676089737;17988.28046878125;18156.273749050022;18413.17140728667;18425.90787046066;18515.35196268131;18678.53303667409;5354.812581559656;5375.489328513785;5447.782088716446;5521.668699878021;5528.336599608053;5330.653507521999;5390.676559406448;5391.16436952273;5425.511703946644;5441.29263195784;5136.974315128419;5278.1291086970605;5280.867978300535;5302;5362.490563396471;5296.443873393296;5299.60252981026;5303.124984377573;5315.548178404841;5341.1988201769855;4683.227267500858;4819.975900120512;4924.532169443897;4926.827561035347;4930.827421040246;21357.67963480549;21448.463788405257;21582.24462143833;21713.348599542038;21809.345719628436;1652;1663.4926347464018;1673.272126624922;1681.5964168599571;1682.3691115831564;14064.648383790385;14293.928530357281;14426.567202983928;14557.70884582313;14728.631784205374;2050.4361300642295;2061.391889392618;2091.588177979873;2135.551534177809;2141.5609799990966;80729.19270807276;80819;80862.19137808605;82393.17606823915;82822.58588707077;10644.308119972176;10734.624288149877;10967.561297548009;11076.280041797258;11111.666650000512;15168.393264269305;15264.542063738105;15265;15285.312160952784;15295.158766267961;7192.964035179791;7337.70649174027;7346.118465784033;7565.962170189224;7568.356689681329;6690.832729181712;6799.3200679931615;6934.237233904267;6934.306569343126;6971.651417429108;6753.561018533779;6755.493337999571;6772.796816095525;6947.89578156328;6978.232394436608;1991.4324417540931;2000.6698894682215;2003.7294965179515;2027.4627223785708;2041.3569725536408;880.8150288439499;906.981860362789;907.26964793342;927.2628260532892;948.8434408322687;16721.414750483676;16907.239174237187;16972.40596579114;17126.743098853527;17699.646007080042;940.6425558287807;947.673052796784;950.4819873169139;952.5189779161618;953.9537691651369;9019.098090190928;9491.145796878314;9581.473018984023;9601.102748380637;9694.272929530314;343986;345092;349770;350226;350579;74188.62905685438;74425;75643;77581.61209193918;77627;1091.7488977535052;1116.7654792493504;1135.7047167736282;1144.2047776795048;1146.7591805720892;14296.928515357356;14332.713345732818;14385.64035899111;14559.12645241289;15099.924500377429;37585;37587.62412375841;37666.811665941495;38186;38381.6161838378;48447;48810.26784598305;50067.74966125146;50149;50197;7780.144184139627;7969.72105976288;8130.63412146443;8184.386171037077;8193.262606365455;11853.170278080797;11870.940645297063;11915.880841191478;12429.37853107323;12529.122961392979;10104.292699510963;10419.218558607983;10444.895551044392;10617.362958222531;10699.94650026745;9248.676296329866;9820.754481138028;10109.79780404373;10263.384196948207;10408.063274305348;10113;10249.9487502562;10582;10588.10001149911;10700.518476668412;10505.15958723285;10901.945490272816;11048.337099774039;11257.380844053656;11630.6510804676;10072.949635251776;10421.9478902605;10477.31897426665;10481.056704896597;10716.839247411139 16:25:52 INFO - PID 3519 | |3;mootools.html;1273.2233337664027;1323.8279023726914;1347.9595612131639;1357.0636260979918;1362.15546361256;9736.70789876303;9994.150497207727;10101.898981010188;10154.644587439441;10262.692119236435;9913.553890074945;10086.69739907802;10093.747656308597;10099.545520451578;10108.74728131797;2199.1643175593254;2223.9332820015425;2245.2478419729327;2248.4491299631613;2249.4826189976316;1993.6311782320245;2082.916683332665;2088.3734879536078;2088.84333674975;2092.612866619673;2171.6416791229426;2184.6613774864923;2225.2100504320897;2229.832762542816;2235.9217427390045;2102.9158833646634;2155.3964889830795;2158.125958986597;2161.9567608647894;2163.296928498244;1978.4064780565773;2101.558672678739;2106.378618307603;2133.7012818205462;2160.945976350588;9924.751881202956;9976.750581235454;10030.047145521165;10031.69904902854;10032.24758143135;5550.833474995757;5788.82633520995;5806.187133801272;5814.97092514539;5820.097884827858;3291.588551431071;3421.726261899042;3522.682958533744;3530.135116896351;3539.4159963605975;1111.4387234446554;1142.2974870454693;1144.599390213427;1175.917685761997;1177.5348737248823;14818.481353152589;14900.776488352683;14944.62638434037;15006.474773382879;15065.246737663074;4540.77296135192;4662.813487460531;4799.880002999953;4905.117078925828;4955.529224723646;10792.136629069655;10803.513841877135;10819.242653014371;10842.295250808673;10848.566057357617;5619.269494965628;5622.943770562242;5624.128260119688;5624.943750562524;5639.182318563785;5033.899322013613;5202.485365512493;5236.790528378822;5274.7890084396195;5292.947070529245;3611.4041183204477;3693.741438099361;3756.079760458705;3762.6425489578446;3829.4830197922925;1072.3940973350032;1079.1205167788228;1085.9945700271448;1098.3958822647512;1109.750306181119;667.3793372163838;681.6114814555755;685.6229074009275;707.4906067631297;709.4537206351081;5051.1413059779925;5066.240063990431;5087.135187018142;5289.127293996525;5316.867078323073;563.9464250896158;571.1689491789436;576.5387689848104;580.8634970781948;581.1399129288676;3193.4890417533084;3197.2646291353117;3345.464725643934;3943.9014024649614;4127.814248358771;13259.535916243078;13313.201207927556;13376.46494140243;13437.932810335886;13544.796828047587;13180.275084870233;13583;13609.183448993092;13643.590692279247;13658.590242292747;7838.8432231355055;8061.556614386148;8067.677292908335;8071.959640201761;8080.313173380328;6128.774245150898;6181.3200547938795;6193.194884665145;6199.225096862892;6240.313565507881;11433.828492572784;11686.649400517997;11981.28112313264;12021.459034343301;12286.017118630667;11754.588589399329;11846;11918.404079795802;12020.519179233082;12067.396630168632;8043.115257321807;8370.58147092655;8373.864664510511;8385.53879536607;8396.118407567117;9368.297377696565;9479.715608531755;9504.572294246915;9598.040195980346;9654.179394751525;7774.668087606728;7916.960415197887;8183.222593853575;8266.214709602578;8288.129746376544;7913.802154945942;7965.4822436541435;8099.797505062184;8159.632816523389;8169.468984515987;6819.829504262235;7021.1574611047;7062.258462861326;7088.326608972139;7389.556626602421;5042.520960508746;5059.165237735732;5200.089984252665;5353.116735738559;5488.341399032141;5038.974805125951;5117.5394214520875;5207.609429292743;5211.661242019257;5276.076686579757 16:25:52 INFO - PID 3519 | |4;prototype.html;371.62094663443287;396.99956110601283;401.3578274760384;403.21373322022066;404.3409242934017;5487.64330318529;5635.126555383917;5643.746031428585;5695.259616249882;5711.428857114286;5412.323459567554;5476.561875049996;5483.479069488402;5504.504594586487;5512;4106.486689163855;4130.566290539495;4130.772807495601;4134.772587507701;4139.979300103495;4076.14400975794;4111.732737372076;4150.667946564283;4157.3140431828715;4158.97920510397;4073.572274911136;4082.9795851020854;4089.3048181809013;4091.4476545666403;4135.214309281242;4080.755154690728;4084.9183016339507;4096.283150448689;4098;4106.342985122396;3568.6082427853157;3581.606023337431;3583.8924832255075;3602.2795440911923;3602.7478076534653;5090.261912022736;5266.15741481365;5270.894582108374;5278.102722537178;5280.313559237313;4877.707337559722;4878.926816097762;4884.460080757986;4902.681325713817;4904.945436731085;5751.626144300607;5841.328247251536;5843.386444423317;5843.3864444233595;5844.649321040752;4040.4343391925154;4052.5947405259535;4054.553999060101;4072.246634372621;4100.077482566428;6001.609895356788;6140.171076904606;6190.876182476325;6222.875542489125;6229.87540249197;4143.901866005511;4152.937705934413;4155.4182414462;4155.958440415558;4170.562090980464;4223.809928553223;4228.203013719157;4235.131797981407;4235.851745188904;4249.957500425018;4731.195696731564;4830.178869592178;4890.193118135468;4891.388576427947;4900;5863.70681465925;5870.7945221917025;5874.882502349929;5880.323762767336;5889.293284805851;1435.1532595768547;1467.0977348930544;1467.618419211013;1467.8091848059685;1472.4625511688157;951.833429149896;956.0964888180741;958.4249450329746;964.392432767352;975.9560819763128;5140.228965655182;5142.6086869605815;5148.330717006766;5159.896802063938;5161.329027226437;6080.179175811298;6095.908561371583;6101.054336577838;6126.693665316717;6141.232345956755;4946.975265123723;5136.203888397305;5150.27896094547;5160.845174644766;5165.14775062118;3211.807291562514;3227.451333273349;3236.385086833494;3239;3244.951325730116;5443.836684899459;5624.3250809903075;5643.915341269884;5654.886902262014;5657.717114144359;6233.283172435228;6233.812985610439;6237.719302631302;6245.940138909745;6268.529860260499;5654.861551838687;5658.292713410824;5658.858528536737;5672.4894759471845;5676.574256930747;5835.56233282514;6010.519158467225;6026.909596356146;6056.939430605814;6080.817575472743;5793.565482588907;5963.522918166623;5988.610740301867;6009.45914867664;6012.488938440107;5615.859603509945;5652.830415087554;5659.151127330933;5659.320881494247;5665.518430933417;4048.595140485928;4055.959440405676;4062.979685101556;4065.959340406558;4088.8773336799945;5936.851578710567;6066.363031881765;6124.663143526971;6124.989376752964;6133.907991380043;5854.687398477663;5856.736446859987;5869.178315035813;5877.471027607536;5880.794172203952;5763.308402991668;5813.709314534341;5847.26909136358;5850.795222167204;5862.472377486047;5729.656220626609;5732.71336433185;5733;5768.884622307614;5791.420857914175;5841.211436456024;5855.180274761452;5858.8242352729485;5907.409259074058;5909.788493358937;5643.560891972521;5684.971575142098;5710.972025035368;5715.199872017838;5719.599628026164 16:25:52 INFO - PID 3519 | |5;yui.html;735.8601865645524;749.209583888997;753.834156485573;770.522276188763;772.826114124322;10941.070009049241;11323.490442930068;13307.80038299425;14163.150210987335;14172.291385430739;14005.29973501326;14783.556493305217;14828.110313381205;14889.925550372232;15038.473653422132;861.7285555050155;888.626776753765;889.9199072083511;894.2622336572322;894.7047474333455;893.6827426263648;899.3389858454032;901.1619194149439;901.535709109809;902.3503077783985;937.7561833923195;938.4228699349885;941.1764705882352;941.5480569326727;944.4003058058114;928.7631653928273;929.3355250995571;934.3833070173653;935.948522831244;937.8405671035906;684.551618689759;686.5503095472474;686.9587824730532;687.3264201082917;688.90355350251;13095.47618095275;13172.34138293091;13234.66913327165;13753.79369309461;14002.57992260234;15646.608834779108;15756.842431575653;15997.680046399008;16111;16156.676866462605;12544.81182782259;12553.246805191719;12707.809382859172;12730.17253878495;12740.426680799386;2990.925226869324;2994.9101526954228;2996.955045674317;3000.6549246836676;3010.6387233531896;62438.37561624371;63345;63592;64527.354726452606;65352.3464765351;12054.216475929037;12327.876721232853;12419.565315213924;12427.130100893031;12480.500779968881;22321.441963950707;22338.21816236424;22436.326910192394;22708.318750437513;22860.65709014366;6258.249010118724;6260.060990851409;6336.334684858116;6354.491640668735;6357.396047375492;2977.344984103494;2980.910572682823;2998.685138060516;3040.0271912987632;3049.344390955955;3497.667721566447;3597.7661452005536;3608.079939615434;3614.2771445710964;3699.5190625218547;722.844588413483;736.6684991753732;739.8298391370015;739.9307999940035;745.1356426545182;607.9878402431926;626.5488848029412;632.339205530222;633.4109278371067;652.2629428745487;9272.072792720808;10456.111230545488;10487.423191724376;10703.304285221435;10993.395363255098;622.2750495672582;634.5241069198102;642.6272761798145;648.4747354642755;662.3178126529682;4407.096545208225;4507.414036175276;4559.293309537027;4580.312953057068;4719.575238228507;59852.70073649604;60516.092258616154;61008.69495652493;61447.38552614506;63198.68400657967;56553;57291;57654.7117264411;58148.70925645429;58419.41580584225;49620.50379496231;51153.48846511562;51346;51656;52696.20955685668;8823.382363234512;10157.542910568895;10503.94748026255;10618.1505479563;10677.46612669379;26176.214713558624;26229.868850655625;26235.081772137884;26402.207933762016;26579.867100664374;42147;43344.349834753135;43618.56381436145;43968.56031439645;45345.54654453545;12701.872981270439;12807.93596032014;12866.871331286568;13034.283114428797;13053.934730326668;15357;15641;15682.215889205721;15795.131267780382;16147.919260403623;13444.932775336061;14194.645133871736;14646.560603181922;14944.626384340478;14953.02805317651;13619.387127579084;14035.719285614436;14110.223937683526;14269.429222831386;14401.207933563755;14005.579832605039;14364.497242596459;14664.853351466349;14690.853091468949;15088;10087.193024558164;10139.645112421325;10263.692089237335;10379.06588407047;10579.5239214234;11994.460249288628;12037.699057523632;12361.443735031766;12677.619671409873;12774.936125319315 16:25:52 INFO - PID 3519 | __end_tp_report 16:25:52 INFO - PID 3519 | __start_cc_report 16:25:52 INFO - PID 3519 | _x_x_mozilla_cycle_collect,369 16:25:52 INFO - PID 3519 | __end_cc_report 16:25:52 INFO - PID 3519 | __startTimestamp1494372352249__endTimestamp 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | ------- Summary: start ------- 16:25:52 INFO - PID 3519 | Number of tests: 6 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | [#0] dojo.html Cycles:165 Average:5248.81 Median:5382.65 stddev:622.51 (11.6%) stddev-sans-first:592.08 16:25:52 INFO - PID 3519 | Values: 2724.1 2770.6 2796.1 2811.2 2811.7 5222.9 5308.9 5326.8 5369.4 5382.7 5284.1 5290.6 5292.2 5357.0 5373.5 4717.2 4728.5 4743.1 4754.3 4803.0 4790.1 4810.4 4829.2 4837.5 4859.5 4599.4 4652.1 4663.4 4726.0 4758.3 4691.4 4692.3 4698.5 4739.8 4751.3 4649.5 4669.9 4676.4 4692.9 4714.3 5075.9 5116.4 5173.9 5181.8 5190.9 5744.5 5772.9 5792.0 5803.4 5837.1 5804.7 5805.4 5808.2 5816.5 5819.0 5167.1 5236.7 5389.6 5390.7 5394.6 5772.9 5838.3 5859.2 5894.9 5902.7 5598.6 5658.5 5661.5 5714.7 5737.9 5752.0 5759.0 5780.2 5780.7 5802.7 5710.0 5713.8 5714.8 5729.9 5731.6 5710.0 5734.5 5738.5 5743.6 5748.0 4655.2 4724.5 4724.9 4730.2 4732.1 4059.5 4060.1 4082.8 4188.8 4190.7 4968.7 4975.6 4990.1 5001.9 5016.4 5794.6 5802.7 5809.4 5812.4 5819.8 4874.2 4949.0 4949.8 4958.3 4975.3 4978.1 5055.6 5063.6 5067.5 5072.3 5350.0 5350.6 5355.9 5357.3 5358.4 5649.4 5754.1 5760.3 5763.5 5768.3 5307.3 5327.7 5342.1 5353.3 5371.4 5601.9 5650.2 5655.0 5691.4 5700.0 5707.5 5709.8 5714.3 5738.1 5796.9 5569.8 5570.0 5576.5 5578.6 5581.3 5513.9 5560.1 5602.8 5605.6 5619.4 5558.7 5568.6 5570.6 5578.1 5581.0 5685.3 5691.1 5699.2 5703.2 5703.3 5361.9 5428.0 5434.8 5503.2 5510.6 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | [#1] ext.html Cycles:180 Average:30004.45 Median:16418.96 stddev:39800.01 (242.4%) stddev-sans-first:39850.31 16:25:52 INFO - PID 3519 | Values: 576.3 619.1 640.8 1183.8 1206.1 34165.3 34954.3 35036.5 35050.5 35099.5 32579.3 33234.3 33234.7 33301.8 33321.7 6821.4 6931.2 6940.5 6940.8 6951.6 6863.6 6929.6 6934.7 6945.5 6972.4 6913.8 6933.7 6947.3 6966.5 6982.7 6888.7 6938.8 6954.7 6975.9 6984.6 6806.4 6822.9 6854.1 6854.7 6866.3 31447.8 31975.0 32078.4 32303.7 32360.9 3047.4 3088.1 3091.8 3091.8 3092.5 24276.0 24322.0 24352.3 24424.8 24578.1 2977.3 2985.2 3003.6 3008.2 3019.7 149041.0 149118.0 149279.3 149694.0 155957.2 40807.6 41092.4 41093.0 41097.8 41149.0 42275.8 42549.0 44583.8 45274.0 45500.0 12634.7 12754.1 12778.0 12784.7 12794.4 9921.4 9988.5 9997.3 10018.4 10048.7 12972.2 13011.0 13060.8 13098.3 13209.1 5434.2 5534.9 5560.8 5561.7 5595.6 880.4 914.7 931.1 943.3 952.1 20781.7 21165.9 21465.7 21745.5 22076.8 986.0 1014.9 1019.3 1027.3 1028.3 11010.6 11355.6 11371.2 11405.4 11536.4 167409.0 168493.0 169480.0 169566.0 171651.0 40642.6 41049.4 41198.6 41212.6 41257.0 1198.9 1265.9 1269.9 1272.6 1278.0 19673.4 19761.7 20491.7 21085.7 21234.5 29635.0 29743.4 29780.7 29809.3 29852.7 140763.0 141655.0 141897.0 142475.3 142740.0 28607.1 28842.3 28844.6 29024.3 29116.9 35830.0 35894.5 36045.6 36077.3 36180.0 25889.3 27247.2 27648.9 27916.4 27947.3 27105.2 27214.3 27713.9 28703.1 28736.1 29511.0 29702.7 29912.8 30197.5 30544.4 26393.6 26495.0 26500.9 26907.3 27912.0 21019.3 21774.5 21861.0 21975.2 22133.3 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | [#2] jquery.html Cycles:180 Average:23921.28 Median:349998.00 stddev:57918.29 (16.5%) stddev-sans-first:58053.88 16:25:52 INFO - PID 3519 | Values: 417.3 436.4 439.5 441.1 448.2 16910.2 17182.9 17844.7 17877.5 17988.3 18156.3 18413.2 18425.9 18515.4 18678.5 5354.8 5375.5 5447.8 5521.7 5528.3 5330.7 5390.7 5391.2 5425.5 5441.3 5137.0 5278.1 5280.9 5302.0 5362.5 5296.4 5299.6 5303.1 5315.5 5341.2 4683.2 4820.0 4924.5 4926.8 4930.8 21357.7 21448.5 21582.2 21713.3 21809.3 1652.0 1663.5 1673.3 1681.6 1682.4 14064.6 14293.9 14426.6 14557.7 14728.6 2050.4 2061.4 2091.6 2135.6 2141.6 80729.2 80819.0 80862.2 82393.2 82822.6 10644.3 10734.6 10967.6 11076.3 11111.7 15168.4 15264.5 15265.0 15285.3 15295.2 7193.0 7337.7 7346.1 7566.0 7568.4 6690.8 6799.3 6934.2 6934.3 6971.7 6753.6 6755.5 6772.8 6947.9 6978.2 1991.4 2000.7 2003.7 2027.5 2041.4 880.8 907.0 907.3 927.3 948.8 16721.4 16907.2 16972.4 17126.7 17699.6 940.6 947.7 950.5 952.5 954.0 9019.1 9491.1 9581.5 9601.1 9694.3 343986.0 345092.0 349770.0 350226.0 350579.0 74188.6 74425.0 75643.0 77581.6 77627.0 1091.7 1116.8 1135.7 1144.2 1146.8 14296.9 14332.7 14385.6 14559.1 15099.9 37585.0 37587.6 37666.8 38186.0 38381.6 48447.0 48810.3 50067.7 50149.0 50197.0 7780.1 7969.7 8130.6 8184.4 8193.3 11853.2 11870.9 11915.9 12429.4 12529.1 10104.3 10419.2 10444.9 10617.4 10699.9 9248.7 9820.8 10109.8 10263.4 10408.1 10113.0 10249.9 10582.0 10588.1 10700.5 10505.2 10901.9 11048.3 11257.4 11630.7 10072.9 10421.9 10477.3 10481.1 10716.8 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | [#3] mootools.html Cycles:180 Average:6254.03 Median:3759.36 stddev:4046.03 (107.6%) stddev-sans-first:4040.07 16:25:52 INFO - PID 3519 | Values: 1273.2 1323.8 1348.0 1357.1 1362.2 9736.7 9994.2 10101.9 10154.6 10262.7 9913.6 10086.7 10093.7 10099.5 10108.7 2199.2 2223.9 2245.2 2248.4 2249.5 1993.6 2082.9 2088.4 2088.8 2092.6 2171.6 2184.7 2225.2 2229.8 2235.9 2102.9 2155.4 2158.1 2162.0 2163.3 1978.4 2101.6 2106.4 2133.7 2160.9 9924.8 9976.8 10030.0 10031.7 10032.2 5550.8 5788.8 5806.2 5815.0 5820.1 3291.6 3421.7 3522.7 3530.1 3539.4 1111.4 1142.3 1144.6 1175.9 1177.5 14818.5 14900.8 14944.6 15006.5 15065.2 4540.8 4662.8 4799.9 4905.1 4955.5 10792.1 10803.5 10819.2 10842.3 10848.6 5619.3 5622.9 5624.1 5624.9 5639.2 5033.9 5202.5 5236.8 5274.8 5292.9 3611.4 3693.7 3756.1 3762.6 3829.5 1072.4 1079.1 1086.0 1098.4 1109.8 667.4 681.6 685.6 707.5 709.5 5051.1 5066.2 5087.1 5289.1 5316.9 563.9 571.2 576.5 580.9 581.1 3193.5 3197.3 3345.5 3943.9 4127.8 13259.5 13313.2 13376.5 13437.9 13544.8 13180.3 13583.0 13609.2 13643.6 13658.6 7838.8 8061.6 8067.7 8072.0 8080.3 6128.8 6181.3 6193.2 6199.2 6240.3 11433.8 11686.6 11981.3 12021.5 12286.0 11754.6 11846.0 11918.4 12020.5 12067.4 8043.1 8370.6 8373.9 8385.5 8396.1 9368.3 9479.7 9504.6 9598.0 9654.2 7774.7 7917.0 8183.2 8266.2 8288.1 7913.8 7965.5 8099.8 8159.6 8169.5 6819.8 7021.2 7062.3 7088.3 7389.6 5042.5 5059.2 5200.1 5353.1 5488.3 5039.0 5117.5 5207.6 5211.7 5276.1 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | [#4] prototype.html Cycles:180 Average:4809.28 Median:5508.25 stddev:1437.61 (26.1%) stddev-sans-first:1402.53 16:25:52 INFO - PID 3519 | Values: 371.6 397.0 401.4 403.2 404.3 5487.6 5635.1 5643.7 5695.3 5711.4 5412.3 5476.6 5483.5 5504.5 5512.0 4106.5 4130.6 4130.8 4134.8 4140.0 4076.1 4111.7 4150.7 4157.3 4159.0 4073.6 4083.0 4089.3 4091.4 4135.2 4080.8 4084.9 4096.3 4098.0 4106.3 3568.6 3581.6 3583.9 3602.3 3602.7 5090.3 5266.2 5270.9 5278.1 5280.3 4877.7 4878.9 4884.5 4902.7 4904.9 5751.6 5841.3 5843.4 5843.4 5844.6 4040.4 4052.6 4054.6 4072.2 4100.1 6001.6 6140.2 6190.9 6222.9 6229.9 4143.9 4152.9 4155.4 4156.0 4170.6 4223.8 4228.2 4235.1 4235.9 4250.0 4731.2 4830.2 4890.2 4891.4 4900.0 5863.7 5870.8 5874.9 5880.3 5889.3 1435.2 1467.1 1467.6 1467.8 1472.5 951.8 956.1 958.4 964.4 976.0 5140.2 5142.6 5148.3 5159.9 5161.3 6080.2 6095.9 6101.1 6126.7 6141.2 4947.0 5136.2 5150.3 5160.8 5165.1 3211.8 3227.5 3236.4 3239.0 3245.0 5443.8 5624.3 5643.9 5654.9 5657.7 6233.3 6233.8 6237.7 6245.9 6268.5 5654.9 5658.3 5658.9 5672.5 5676.6 5835.6 6010.5 6026.9 6056.9 6080.8 5793.6 5963.5 5988.6 6009.5 6012.5 5615.9 5652.8 5659.2 5659.3 5665.5 4048.6 4056.0 4063.0 4066.0 4088.9 5936.9 6066.4 6124.7 6125.0 6133.9 5854.7 5856.7 5869.2 5877.5 5880.8 5763.3 5813.7 5847.3 5850.8 5862.5 5729.7 5732.7 5733.0 5768.9 5791.4 5841.2 5855.2 5858.8 5907.4 5909.8 5643.6 5685.0 5711.0 5715.2 5719.6 16:25:52 INFO - PID 3519 | 16:25:52 INFO - PID 3519 | [#5] yui.html Cycles:180 Average:15302.03 Median:3025.33 stddev:17678.34 (584.3%) stddev-sans-first:17694.09 16:25:52 INFO - PID 3519 | Values: 735.9 749.2 753.8 770.5 772.8 10941.1 11323.5 13307.8 14163.2 14172.3 14005.3 14783.6 14828.1 14889.9 15038.5 861.7 888.6 889.9 894.3 894.7 893.7 899.3 901.2 901.5 902.4 937.8 938.4 941.2 941.5 944.4 928.8 929.3 934.4 935.9 937.8 684.6 686.6 687.0 687.3 688.9 13095.5 13172.3 13234.7 13753.8 14002.6 15646.6 15756.8 15997.7 16111.0 16156.7 12544.8 12553.2 12707.8 12730.2 12740.4 2990.9 2994.9 2997.0 3000.7 3010.6 62438.4 63345.0 63592.0 64527.4 65352.3 12054.2 12327.9 12419.6 12427.1 12480.5 22321.4 22338.2 22436.3 22708.3 22860.7 6258.2 6260.1 6336.3 6354.5 6357.4 2977.3 2980.9 2998.7 3040.0 3049.3 3497.7 3597.8 3608.1 3614.3 3699.5 722.8 736.7 739.8 739.9 745.1 608.0 626.5 632.3 633.4 652.3 9272.1 10456.1 10487.4 10703.3 10993.4 622.3 634.5 642.6 648.5 662.3 4407.1 4507.4 4559.3 4580.3 4719.6 59852.7 60516.1 61008.7 61447.4 63198.7 56553.0 57291.0 57654.7 58148.7 58419.4 49620.5 51153.5 51346.0 51656.0 52696.2 8823.4 10157.5 10503.9 10618.2 10677.5 26176.2 26229.9 26235.1 26402.2 26579.9 42147.0 43344.3 43618.6 43968.6 45345.5 12701.9 12807.9 12866.9 13034.3 13053.9 15357.0 15641.0 15682.2 15795.1 16147.9 13444.9 14194.6 14646.6 14944.6 14953.0 13619.4 14035.7 14110.2 14269.4 14401.2 14005.6 14364.5 14664.9 14690.9 15088.0 10087.2 10139.6 10263.7 10379.1 10579.5 11994.5 12037.7 12361.4 12677.6 12774.9 16:25:52 INFO - PID 3519 | -------- Summary: end -------- 16:25:52 INFO - PID 3519 | 16:25:53 INFO - TEST-INFO | 3519: exit 0 16:25:53 INFO - TEST-OK | dromaeo_css | took 1120761ms 16:25:53 INFO - TEST-START | kraken 16:25:53 INFO - operating with platform_type : linux_ 16:25:53 INFO - Initialising browser for kraken test... 16:25:53 INFO - TEST-INFO | started process 3954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprc5OwE/profile http://localhost:58517/getInfo.html) 16:25:56 INFO - PID 3954 | __metrics Screen width/height:1600/1200 16:25:56 INFO - PID 3954 | colorDepth:24 16:25:56 INFO - PID 3954 | Browser inner width/height: 1024/695 16:25:56 INFO - PID 3954 | __metrics 16:25:58 INFO - TEST-INFO | 3954: exit 0 16:25:58 INFO - Browser initialized. 16:25:58 INFO - Running cycle 1/1 for kraken test... 16:25:58 INFO - TEST-INFO | started process 4147 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprc5OwE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:26:10 INFO - PID 4147 | RSS: Main: 169283584 16:26:10 INFO - PID 4147 | 16:26:13 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58517/tests/kraken/driver.html?testName=audio-beat-detection) 16:26:14 INFO - PID 4147 | RSS: Main: 178368512 16:26:14 INFO - PID 4147 | 16:26:17 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58517/tests/kraken/driver.html?testName=audio-dft) 16:26:17 INFO - PID 4147 | RSS: Main: 177606656 16:26:17 INFO - PID 4147 | 16:26:20 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58517/tests/kraken/driver.html?testName=audio-fft) 16:26:21 INFO - PID 4147 | RSS: Main: 173592576 16:26:21 INFO - PID 4147 | 16:26:23 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58517/tests/kraken/driver.html?testName=audio-oscillator) 16:26:23 INFO - PID 4147 | RSS: Main: 174071808 16:26:23 INFO - PID 4147 | 16:26:26 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58517/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:26:26 INFO - PID 4147 | RSS: Main: 175022080 16:26:26 INFO - PID 4147 | 16:26:29 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58517/tests/kraken/driver.html?testName=imaging-darkroom) 16:26:30 INFO - PID 4147 | RSS: Main: 179154944 16:26:30 INFO - PID 4147 | 16:26:34 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58517/tests/kraken/driver.html?testName=imaging-desaturate) 16:26:34 INFO - PID 4147 | RSS: Main: 177532928 16:26:34 INFO - PID 4147 | 16:26:37 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58517/tests/kraken/driver.html?testName=json-parse-financial) 16:26:38 INFO - PID 4147 | RSS: Main: 178339840 16:26:38 INFO - PID 4147 | 16:26:39 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58517/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:26:40 INFO - PID 4147 | RSS: Main: 180117504 16:26:40 INFO - PID 4147 | 16:26:42 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:26:42 INFO - PID 4147 | RSS: Main: 180908032 16:26:42 INFO - PID 4147 | 16:26:45 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:26:45 INFO - PID 4147 | RSS: Main: 180363264 16:26:45 INFO - PID 4147 | 16:26:48 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:26:49 INFO - PID 4147 | RSS: Main: 180551680 16:26:49 INFO - PID 4147 | 16:26:52 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:26:52 INFO - PID 4147 | RSS: Main: 181018624 16:26:52 INFO - PID 4147 | 16:26:54 INFO - PID 4147 | Cycle 1(1): loaded http://localhost:58517/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58517/tests/kraken/driver.html?testName=ai-astar) 16:26:55 INFO - PID 4147 | RSS: Main: 182984704 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | __start_tp_report 16:26:55 INFO - PID 4147 | _x_x_mozilla_page_load 16:26:55 INFO - PID 4147 | _x_x_mozilla_page_load_details 16:26:55 INFO - PID 4147 | |i|pagename|runs| 16:26:55 INFO - PID 4147 | |0;ai-astar;105;116;111;111;111;118;112;112;111;112 16:26:55 INFO - PID 4147 | |1;audio-beat-detection;154;129;122;179;122;128;123;121;142;123 16:26:55 INFO - PID 4147 | |2;audio-dft;164;162;166;165;168;167;168;162;163;162 16:26:55 INFO - PID 4147 | |3;audio-fft;75;76;75;107;75;76;76;75;93;82 16:26:55 INFO - PID 4147 | |4;audio-oscillator;83;86;104;83;84;83;96;87;83;101 16:26:55 INFO - PID 4147 | |5;imaging-gaussian-blur;114;114;114;114;114;114;114;117;114;114 16:26:55 INFO - PID 4147 | |6;imaging-darkroom;150;149;150;151;152;153;151;149;150;150 16:26:55 INFO - PID 4147 | |7;imaging-desaturate;94;94;95;95;94;94;94;95;94;94 16:26:55 INFO - PID 4147 | |8;json-parse-financial;54;53;55;55;60;59;55;54;54;53 16:26:55 INFO - PID 4147 | |9;json-stringify-tinderbox;55;55;55;56;56;56;56;56;57;56 16:26:55 INFO - PID 4147 | |10;stanford-crypto-aes;82;83;81;81;83;82;83;80;82;82 16:26:55 INFO - PID 4147 | |11;stanford-crypto-ccm;130;131;131;135;134;130;131;132;176;130 16:26:55 INFO - PID 4147 | |12;stanford-crypto-pbkdf2;183;194;183;188;180;189;189;192;182;184 16:26:55 INFO - PID 4147 | |13;stanford-crypto-sha256-iterative;70;74;72;72;71;70;74;69;71;70 16:26:55 INFO - PID 4147 | __end_tp_report 16:26:55 INFO - PID 4147 | __start_cc_report 16:26:55 INFO - PID 4147 | _x_x_mozilla_cycle_collect,851 16:26:55 INFO - PID 4147 | __end_cc_report 16:26:55 INFO - PID 4147 | __startTimestamp1494372415049__endTimestamp 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | ------- Summary: start ------- 16:26:55 INFO - PID 4147 | Number of tests: 14 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:3.41 (3.0%) stddev-sans-first:2.55 16:26:55 INFO - PID 4147 | Values: 105.0 116.0 111.0 111.0 111.0 118.0 112.0 112.0 111.0 112.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:128.50 stddev:19.00 (14.8%) stddev-sans-first:18.76 16:26:55 INFO - PID 4147 | Values: 154.0 129.0 122.0 179.0 122.0 128.0 123.0 121.0 142.0 123.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#2] audio-dft Cycles:10 Average:164.70 Median:165.50 stddev:2.45 (1.5%) stddev-sans-first:2.59 16:26:55 INFO - PID 4147 | Values: 164.0 162.0 166.0 165.0 168.0 167.0 168.0 162.0 163.0 162.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:10.75 (14.1%) stddev-sans-first:11.18 16:26:55 INFO - PID 4147 | Values: 75.0 76.0 75.0 107.0 75.0 76.0 76.0 75.0 93.0 82.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:83.50 stddev:8.16 (9.8%) stddev-sans-first:8.37 16:26:55 INFO - PID 4147 | Values: 83.0 86.0 104.0 83.0 84.0 83.0 96.0 87.0 83.0 101.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 16:26:55 INFO - PID 4147 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 114.0 117.0 114.0 114.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:150.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 16:26:55 INFO - PID 4147 | Values: 150.0 149.0 150.0 151.0 152.0 153.0 151.0 149.0 150.0 150.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:26:55 INFO - PID 4147 | Values: 94.0 94.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 94.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:2.39 (4.4%) stddev-sans-first:2.50 16:26:55 INFO - PID 4147 | Values: 54.0 53.0 55.0 55.0 60.0 59.0 55.0 54.0 54.0 53.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:56.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 16:26:55 INFO - PID 4147 | Values: 55.0 55.0 55.0 56.0 56.0 56.0 56.0 56.0 57.0 56.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 16:26:55 INFO - PID 4147 | Values: 82.0 83.0 81.0 81.0 83.0 82.0 83.0 80.0 82.0 82.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:131.50 stddev:14.16 (10.8%) stddev-sans-first:14.85 16:26:55 INFO - PID 4147 | Values: 130.0 131.0 131.0 135.0 134.0 130.0 131.0 132.0 176.0 130.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:188.50 stddev:4.65 (2.5%) stddev-sans-first:4.76 16:26:55 INFO - PID 4147 | Values: 183.0 194.0 183.0 188.0 180.0 189.0 189.0 192.0 182.0 184.0 16:26:55 INFO - PID 4147 | 16:26:55 INFO - PID 4147 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:71.50 stddev:1.70 (2.4%) stddev-sans-first:1.74 16:26:55 INFO - PID 4147 | Values: 70.0 74.0 72.0 72.0 71.0 70.0 74.0 69.0 71.0 70.0 16:26:55 INFO - PID 4147 | -------- Summary: end -------- 16:26:55 INFO - PID 4147 | 16:26:56 INFO - TEST-INFO | 4147: exit 0 16:26:56 INFO - TEST-OK | kraken | took 63140ms 16:26:56 INFO - SUITE-END | took 1183s 16:26:56 INFO - Completed test suite (00:19:43) 16:26:56 INFO - javascript benchmark 16:26:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6729.855113269639, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2724.114662734611, 2770.5567109262497, 2796.063318788207, 2811.226912599034, 2811.6766571844246, 5222.8694282642955, 5308.867278318044, 5326.813561525348, 5369.4362091980365, 5382.650127741694, 5284.128118860383, 5290.603204759649, 5292.206169074631, 5357.0, 5373.543248823855, 4717.174494463472, 4728.4798672146035, 4743.051389722053, 4754.263089221176, 4803.039392121572, 4790.113828941657, 4810.350602668647, 4829.22732362822, 4837.540433658797, 4859.465458799529, 4599.44806623204, 4652.092841895832, 4663.370444989951, 4725.976370118162, 4758.333833263346, 4691.437027556681, 4692.343071969927, 4698.483166851644, 4739.763011849428, 4751.31105989633, 4649.511801260886, 4669.929951050737, 4676.415448068991, 4692.906141877143, 4714.269288260325, 5075.923861142086, 5116.437191908924, 5173.948260517422, 5181.81863634771, 5190.922136167923, 5744.454276843693, 5772.942270577324, 5792.044312688401, 5803.419658034163, 5837.09525023622, 5804.680742559158, 5805.448482394166, 5808.244928159313, 5816.505597024258, 5818.970905145447, 5167.147420675622, 5236.73816309183, 5389.622726409114, 5390.703511306916, 5394.568434525268, 5772.913406298909, 5838.2702162229725, 5859.20900678403, 5894.852628684317, 5902.704864756745, 5598.636088654224, 5658.519025882763, 5661.46216109469, 5714.657120572779, 5737.913931291034, 5751.971240143856, 5759.0, 5780.219670344533, 5780.6531608102805, 5802.709864506758, 5709.9714501427225, 5713.771449142071, 5714.79998200061, 5729.88540229193, 5731.570132240098, 5710.0, 5734.512566431817, 5738.483536481736, 5743.569232307594, 5747.971260143756, 4655.208614535537, 4724.503927087675, 4724.936889199903, 4730.195866702668, 4732.100900828831, 4059.4722686050623, 4060.0864805418546, 4082.7550346978683, 4188.790560471964, 4190.664746820247, 4968.726720030433, 4975.577075948585, 4990.126727822617, 5001.874953126201, 5016.44819069902, 5794.565407594448, 5802.709864506758, 5809.3609702932645, 5812.447817457335, 5819.82540523785, 4874.19575770001, 4949.0, 4949.802007919643, 4958.305837182797, 4975.328330675313, 4978.079055374767, 5055.595552355802, 5063.594912406998, 5067.518585734423, 5072.289879416885, 5350.0, 5350.5987050971335, 5355.946440535545, 5357.276767636319, 5358.410574836843, 5649.406812284651, 5754.079347304411, 5760.308762948473, 5763.452472015152, 5768.336641286306, 5307.336582927134, 5327.653702509325, 5342.091844386386, 5353.330833645794, 5371.435999220025, 5601.943980560142, 5650.237217975685, 5654.953833540808, 5691.373948865705, 5700.0, 5707.543396528351, 5709.800156994485, 5714.314282286152, 5738.139279108167, 5796.913046304225, 5569.777208911761, 5570.0, 5576.525995290283, 5578.553715702815, 5581.330240371181, 5513.9448605513435, 5560.138178582326, 5602.803901863415, 5605.635633683797, 5619.353774316006, 5558.666480011212, 5568.554515638658, 5570.582206334622, 5578.135389014709, 5580.972095139498, 5685.289338832646, 5691.089425691869, 5699.230603868425, 5703.231998080609, 5703.287089113861, 5361.865953351042, 5428.0, 5434.75543600531, 5503.229547863222, 5510.614257001971], "value": 5203.386890006879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.2537513919474, 619.108483783352, 640.8397900524869, 1183.8046722290817, 1206.1496644865372, 34165.316693666115, 34954.30091398171, 35036.474452883194, 35050.474242886376, 35099.473507897404, 32579.348413031727, 33234.34719139322, 33234.66765332346, 33301.83349083251, 33321.66678333216, 6821.420179284767, 6931.16825980883, 6940.4794640401915, 6940.826479338007, 6951.582905025714, 6863.588184708909, 6929.5842249465195, 6934.687939042755, 6945.47908906834, 6972.372486476216, 6913.7925862223965, 6933.68798404073, 6947.305269473038, 6966.547174433671, 6982.650867456632, 6888.655567221619, 6938.826529336781, 6954.687039083254, 6975.895361569581, 6984.615846128461, 6806.353396427331, 6822.863542729117, 6854.074699915497, 6854.691538880713, 6866.279040700754, 31447.84276078628, 31975.0, 32078.358432831214, 32303.6769632303, 32360.86736964195, 3047.405755877605, 3088.058142266608, 3091.7835751497405, 3091.7990330628436, 3092.4588197065423, 24276.028958841624, 24322.0, 24352.26943191707, 24424.755752442426, 24578.139765108313, 2977.2556860784803, 2985.208919636298, 3003.6245469316336, 3008.1877892968996, 3019.6829332919947, 149041.0, 149118.0, 149279.25360373128, 149694.0, 155957.22021389933, 40807.59192408038, 41092.38361424581, 41093.0, 41097.794511027256, 41149.0, 42275.78862105731, 42549.0, 44583.777081114386, 45274.0, 45500.0, 12634.684132896751, 12754.107212495037, 12778.0, 12784.74430511403, 12794.42425090873, 9921.40471571708, 9988.450635215133, 9997.250206234416, 10018.39889606626, 10048.69853904384, 12972.221666700028, 13011.0, 13060.804087938688, 13098.345082745825, 13209.141405808592, 5434.163594400986, 5534.916976245359, 5560.805371811967, 5561.694106824163, 5595.552355811526, 880.4101252161067, 914.679862048291, 931.0549791961165, 943.2878176976338, 952.1335584617964, 20781.688274675893, 21165.89417052905, 21465.67801482979, 21745.456363591038, 22076.779232207795, 985.9507024648739, 1014.8731408573929, 1019.2508506247901, 1027.2655051638114, 1028.3469996552094, 11010.614628487965, 11355.602553910574, 11371.204015718986, 11405.372704501333, 11536.365499897585, 167409.0, 168493.0, 169480.0, 169566.0, 171651.0, 40642.59357406447, 41049.38425923613, 41198.588014120076, 41212.58787412148, 41257.0, 1198.9100817438728, 1265.8797414245632, 1269.9365031748375, 1272.5609664665676, 1277.9552715655093, 19673.409797706092, 19761.703574446106, 20491.69262461094, 21085.683714743984, 21234.469138271048, 29635.0, 29743.40513189681, 29780.70219297779, 29809.25476863096, 29852.70147298499, 140763.0, 141655.0, 141897.0, 142475.28762356122, 142740.0, 28607.13678674672, 28842.27894302659, 28844.567331489623, 29024.274393140342, 29116.854415727787, 35830.0, 35894.461583076794, 36045.63954360528, 36077.27845443129, 36180.0, 25889.309436526615, 27247.182584522496, 27648.861755691094, 27916.441671166056, 27947.301317467227, 27105.1868443947, 27214.319642009108, 27713.86143069272, 28703.138905832857, 28736.137915862557, 29511.0, 29702.702972969993, 29912.842298410455, 30197.547036794025, 30544.389112218076, 26393.60409593896, 26495.0, 26500.867495662398, 26907.327316817235, 27912.0, 21019.264325748525, 21774.541344661975, 21861.0, 21975.23086691958, 22133.33599992003], "value": 14517.213216422499, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.25936462778543, 436.37598234524626, 439.498971172863, 441.06274167394287, 448.1977260703341, 16910.154492275382, 17182.91408542957, 17844.732329015074, 17877.463676089737, 17988.28046878125, 18156.273749050022, 18413.17140728667, 18425.90787046066, 18515.35196268131, 18678.53303667409, 5354.812581559656, 5375.489328513785, 5447.782088716446, 5521.668699878021, 5528.336599608053, 5330.653507521999, 5390.676559406448, 5391.16436952273, 5425.511703946644, 5441.29263195784, 5136.974315128419, 5278.1291086970605, 5280.867978300535, 5302.0, 5362.490563396471, 5296.443873393296, 5299.60252981026, 5303.124984377573, 5315.548178404841, 5341.1988201769855, 4683.227267500858, 4819.975900120512, 4924.532169443897, 4926.827561035347, 4930.827421040246, 21357.67963480549, 21448.463788405257, 21582.24462143833, 21713.348599542038, 21809.345719628436, 1652.0, 1663.4926347464018, 1673.272126624922, 1681.5964168599571, 1682.3691115831564, 14064.648383790385, 14293.928530357281, 14426.567202983928, 14557.70884582313, 14728.631784205374, 2050.4361300642295, 2061.391889392618, 2091.588177979873, 2135.551534177809, 2141.5609799990966, 80729.19270807276, 80819.0, 80862.19137808605, 82393.17606823915, 82822.58588707077, 10644.308119972176, 10734.624288149877, 10967.561297548009, 11076.280041797258, 11111.666650000512, 15168.393264269305, 15264.542063738105, 15265.0, 15285.312160952784, 15295.158766267961, 7192.964035179791, 7337.70649174027, 7346.118465784033, 7565.962170189224, 7568.356689681329, 6690.832729181712, 6799.3200679931615, 6934.237233904267, 6934.306569343126, 6971.651417429108, 6753.561018533779, 6755.493337999571, 6772.796816095525, 6947.89578156328, 6978.232394436608, 1991.4324417540931, 2000.6698894682215, 2003.7294965179515, 2027.4627223785708, 2041.3569725536408, 880.8150288439499, 906.981860362789, 907.26964793342, 927.2628260532892, 948.8434408322687, 16721.414750483676, 16907.239174237187, 16972.40596579114, 17126.743098853527, 17699.646007080042, 940.6425558287807, 947.673052796784, 950.4819873169139, 952.5189779161618, 953.9537691651369, 9019.098090190928, 9491.145796878314, 9581.473018984023, 9601.102748380637, 9694.272929530314, 343986.0, 345092.0, 349770.0, 350226.0, 350579.0, 74188.62905685438, 74425.0, 75643.0, 77581.61209193918, 77627.0, 1091.7488977535052, 1116.7654792493504, 1135.7047167736282, 1144.2047776795048, 1146.7591805720892, 14296.928515357356, 14332.713345732818, 14385.64035899111, 14559.12645241289, 15099.924500377429, 37585.0, 37587.62412375841, 37666.811665941495, 38186.0, 38381.6161838378, 48447.0, 48810.26784598305, 50067.74966125146, 50149.0, 50197.0, 7780.144184139627, 7969.72105976288, 8130.63412146443, 8184.386171037077, 8193.262606365455, 11853.170278080797, 11870.940645297063, 11915.880841191478, 12429.37853107323, 12529.122961392979, 10104.292699510963, 10419.218558607983, 10444.895551044392, 10617.362958222531, 10699.94650026745, 9248.676296329866, 9820.754481138028, 10109.79780404373, 10263.384196948207, 10408.063274305348, 10113.0, 10249.9487502562, 10582.0, 10588.10001149911, 10700.518476668412, 10505.15958723285, 10901.945490272816, 11048.337099774039, 11257.380844053656, 11630.6510804676, 10072.949635251776, 10421.9478902605, 10477.31897426665, 10481.056704896597, 10716.839247411139], "value": 8751.504520674556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.2233337664027, 1323.8279023726914, 1347.9595612131639, 1357.0636260979918, 1362.15546361256, 9736.70789876303, 9994.150497207727, 10101.898981010188, 10154.644587439441, 10262.692119236435, 9913.553890074945, 10086.69739907802, 10093.747656308597, 10099.545520451578, 10108.74728131797, 2199.1643175593254, 2223.9332820015425, 2245.2478419729327, 2248.4491299631613, 2249.4826189976316, 1993.6311782320245, 2082.916683332665, 2088.3734879536078, 2088.84333674975, 2092.612866619673, 2171.6416791229426, 2184.6613774864923, 2225.2100504320897, 2229.832762542816, 2235.9217427390045, 2102.9158833646634, 2155.3964889830795, 2158.125958986597, 2161.9567608647894, 2163.296928498244, 1978.4064780565773, 2101.558672678739, 2106.378618307603, 2133.7012818205462, 2160.945976350588, 9924.751881202956, 9976.750581235454, 10030.047145521165, 10031.69904902854, 10032.24758143135, 5550.833474995757, 5788.82633520995, 5806.187133801272, 5814.97092514539, 5820.097884827858, 3291.588551431071, 3421.726261899042, 3522.682958533744, 3530.135116896351, 3539.4159963605975, 1111.4387234446554, 1142.2974870454693, 1144.599390213427, 1175.917685761997, 1177.5348737248823, 14818.481353152589, 14900.776488352683, 14944.62638434037, 15006.474773382879, 15065.246737663074, 4540.77296135192, 4662.813487460531, 4799.880002999953, 4905.117078925828, 4955.529224723646, 10792.136629069655, 10803.513841877135, 10819.242653014371, 10842.295250808673, 10848.566057357617, 5619.269494965628, 5622.943770562242, 5624.128260119688, 5624.943750562524, 5639.182318563785, 5033.899322013613, 5202.485365512493, 5236.790528378822, 5274.7890084396195, 5292.947070529245, 3611.4041183204477, 3693.741438099361, 3756.079760458705, 3762.6425489578446, 3829.4830197922925, 1072.3940973350032, 1079.1205167788228, 1085.9945700271448, 1098.3958822647512, 1109.750306181119, 667.3793372163838, 681.6114814555755, 685.6229074009275, 707.4906067631297, 709.4537206351081, 5051.1413059779925, 5066.240063990431, 5087.135187018142, 5289.127293996525, 5316.867078323073, 563.9464250896158, 571.1689491789436, 576.5387689848104, 580.8634970781948, 581.1399129288676, 3193.4890417533084, 3197.2646291353117, 3345.464725643934, 3943.9014024649614, 4127.814248358771, 13259.535916243078, 13313.201207927556, 13376.46494140243, 13437.932810335886, 13544.796828047587, 13180.275084870233, 13583.0, 13609.183448993092, 13643.590692279247, 13658.590242292747, 7838.8432231355055, 8061.556614386148, 8067.677292908335, 8071.959640201761, 8080.313173380328, 6128.774245150898, 6181.3200547938795, 6193.194884665145, 6199.225096862892, 6240.313565507881, 11433.828492572784, 11686.649400517997, 11981.28112313264, 12021.459034343301, 12286.017118630667, 11754.588589399329, 11846.0, 11918.404079795802, 12020.519179233082, 12067.396630168632, 8043.115257321807, 8370.58147092655, 8373.864664510511, 8385.53879536607, 8396.118407567117, 9368.297377696565, 9479.715608531755, 9504.572294246915, 9598.040195980346, 9654.179394751525, 7774.668087606728, 7916.960415197887, 8183.222593853575, 8266.214709602578, 8288.129746376544, 7913.802154945942, 7965.4822436541435, 8099.797505062184, 8159.632816523389, 8169.468984515987, 6819.829504262235, 7021.1574611047, 7062.258462861326, 7088.326608972139, 7389.556626602421, 5042.520960508746, 5059.165237735732, 5200.089984252665, 5353.116735738559, 5488.341399032141, 5038.974805125951, 5117.5394214520875, 5207.609429292743, 5211.661242019257, 5276.076686579757], "value": 4665.4310978649255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.62094663443287, 396.99956110601283, 401.3578274760384, 403.21373322022066, 404.3409242934017, 5487.64330318529, 5635.126555383917, 5643.746031428585, 5695.259616249882, 5711.428857114286, 5412.323459567554, 5476.561875049996, 5483.479069488402, 5504.504594586487, 5512.0, 4106.486689163855, 4130.566290539495, 4130.772807495601, 4134.772587507701, 4139.979300103495, 4076.14400975794, 4111.732737372076, 4150.667946564283, 4157.3140431828715, 4158.97920510397, 4073.572274911136, 4082.9795851020854, 4089.3048181809013, 4091.4476545666403, 4135.214309281242, 4080.755154690728, 4084.9183016339507, 4096.283150448689, 4098.0, 4106.342985122396, 3568.6082427853157, 3581.606023337431, 3583.8924832255075, 3602.2795440911923, 3602.7478076534653, 5090.261912022736, 5266.15741481365, 5270.894582108374, 5278.102722537178, 5280.313559237313, 4877.707337559722, 4878.926816097762, 4884.460080757986, 4902.681325713817, 4904.945436731085, 5751.626144300607, 5841.328247251536, 5843.386444423317, 5843.3864444233595, 5844.649321040752, 4040.4343391925154, 4052.5947405259535, 4054.553999060101, 4072.246634372621, 4100.077482566428, 6001.609895356788, 6140.171076904606, 6190.876182476325, 6222.875542489125, 6229.87540249197, 4143.901866005511, 4152.937705934413, 4155.4182414462, 4155.958440415558, 4170.562090980464, 4223.809928553223, 4228.203013719157, 4235.131797981407, 4235.851745188904, 4249.957500425018, 4731.195696731564, 4830.178869592178, 4890.193118135468, 4891.388576427947, 4900.0, 5863.70681465925, 5870.7945221917025, 5874.882502349929, 5880.323762767336, 5889.293284805851, 1435.1532595768547, 1467.0977348930544, 1467.618419211013, 1467.8091848059685, 1472.4625511688157, 951.833429149896, 956.0964888180741, 958.4249450329746, 964.392432767352, 975.9560819763128, 5140.228965655182, 5142.6086869605815, 5148.330717006766, 5159.896802063938, 5161.329027226437, 6080.179175811298, 6095.908561371583, 6101.054336577838, 6126.693665316717, 6141.232345956755, 4946.975265123723, 5136.203888397305, 5150.27896094547, 5160.845174644766, 5165.14775062118, 3211.807291562514, 3227.451333273349, 3236.385086833494, 3239.0, 3244.951325730116, 5443.836684899459, 5624.3250809903075, 5643.915341269884, 5654.886902262014, 5657.717114144359, 6233.283172435228, 6233.812985610439, 6237.719302631302, 6245.940138909745, 6268.529860260499, 5654.861551838687, 5658.292713410824, 5658.858528536737, 5672.4894759471845, 5676.574256930747, 5835.56233282514, 6010.519158467225, 6026.909596356146, 6056.939430605814, 6080.817575472743, 5793.565482588907, 5963.522918166623, 5988.610740301867, 6009.45914867664, 6012.488938440107, 5615.859603509945, 5652.830415087554, 5659.151127330933, 5659.320881494247, 5665.518430933417, 4048.595140485928, 4055.959440405676, 4062.979685101556, 4065.959340406558, 4088.8773336799945, 5936.851578710567, 6066.363031881765, 6124.663143526971, 6124.989376752964, 6133.907991380043, 5854.687398477663, 5856.736446859987, 5869.178315035813, 5877.471027607536, 5880.794172203952, 5763.308402991668, 5813.709314534341, 5847.26909136358, 5850.795222167204, 5862.472377486047, 5729.656220626609, 5732.71336433185, 5733.0, 5768.884622307614, 5791.420857914175, 5841.211436456024, 5855.180274761452, 5858.8242352729485, 5907.409259074058, 5909.788493358937, 5643.560891972521, 5684.971575142098, 5710.972025035368, 5715.199872017838, 5719.599628026164], "value": 4367.323348343396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.8601865645524, 749.209583888997, 753.834156485573, 770.522276188763, 772.826114124322, 10941.070009049241, 11323.490442930068, 13307.80038299425, 14163.150210987335, 14172.291385430739, 14005.29973501326, 14783.556493305217, 14828.110313381205, 14889.925550372232, 15038.473653422132, 861.7285555050155, 888.626776753765, 889.9199072083511, 894.2622336572322, 894.7047474333455, 893.6827426263648, 899.3389858454032, 901.1619194149439, 901.535709109809, 902.3503077783985, 937.7561833923195, 938.4228699349885, 941.1764705882352, 941.5480569326727, 944.4003058058114, 928.7631653928273, 929.3355250995571, 934.3833070173653, 935.948522831244, 937.8405671035906, 684.551618689759, 686.5503095472474, 686.9587824730532, 687.3264201082917, 688.90355350251, 13095.47618095275, 13172.34138293091, 13234.66913327165, 13753.79369309461, 14002.57992260234, 15646.608834779108, 15756.842431575653, 15997.680046399008, 16111.0, 16156.676866462605, 12544.81182782259, 12553.246805191719, 12707.809382859172, 12730.17253878495, 12740.426680799386, 2990.925226869324, 2994.9101526954228, 2996.955045674317, 3000.6549246836676, 3010.6387233531896, 62438.37561624371, 63345.0, 63592.0, 64527.354726452606, 65352.3464765351, 12054.216475929037, 12327.876721232853, 12419.565315213924, 12427.130100893031, 12480.500779968881, 22321.441963950707, 22338.21816236424, 22436.326910192394, 22708.318750437513, 22860.65709014366, 6258.249010118724, 6260.060990851409, 6336.334684858116, 6354.491640668735, 6357.396047375492, 2977.344984103494, 2980.910572682823, 2998.685138060516, 3040.0271912987632, 3049.344390955955, 3497.667721566447, 3597.7661452005536, 3608.079939615434, 3614.2771445710964, 3699.5190625218547, 722.844588413483, 736.6684991753732, 739.8298391370015, 739.9307999940035, 745.1356426545182, 607.9878402431926, 626.5488848029412, 632.339205530222, 633.4109278371067, 652.2629428745487, 9272.072792720808, 10456.111230545488, 10487.423191724376, 10703.304285221435, 10993.395363255098, 622.2750495672582, 634.5241069198102, 642.6272761798145, 648.4747354642755, 662.3178126529682, 4407.096545208225, 4507.414036175276, 4559.293309537027, 4580.312953057068, 4719.575238228507, 59852.70073649604, 60516.092258616154, 61008.69495652493, 61447.38552614506, 63198.68400657967, 56553.0, 57291.0, 57654.7117264411, 58148.70925645429, 58419.41580584225, 49620.50379496231, 51153.48846511562, 51346.0, 51656.0, 52696.20955685668, 8823.382363234512, 10157.542910568895, 10503.94748026255, 10618.1505479563, 10677.46612669379, 26176.214713558624, 26229.868850655625, 26235.081772137884, 26402.207933762016, 26579.867100664374, 42147.0, 43344.349834753135, 43618.56381436145, 43968.56031439645, 45345.54654453545, 12701.872981270439, 12807.93596032014, 12866.871331286568, 13034.283114428797, 13053.934730326668, 15357.0, 15641.0, 15682.215889205721, 15795.131267780382, 16147.919260403623, 13444.932775336061, 14194.645133871736, 14646.560603181922, 14944.626384340478, 14953.02805317651, 13619.387127579084, 14035.719285614436, 14110.223937683526, 14269.429222831386, 14401.207933563755, 14005.579832605039, 14364.497242596459, 14664.853351466349, 14690.853091468949, 15088.0, 10087.193024558164, 10139.645112421325, 10263.692089237335, 10379.06588407047, 10579.5239214234, 11994.460249288628, 12037.699057523632, 12361.443735031766, 12677.619671409873, 12774.936125319315], "value": 6896.738553280174, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 111.0, 111.0, 111.0, 118.0, 112.0, 112.0, 111.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 129.0, 122.0, 179.0, 122.0, 128.0, 123.0, 121.0, 142.0, 123.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 166.0, 165.0, 168.0, 167.0, 168.0, 162.0, 163.0, 162.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 107.0, 75.0, 76.0, 76.0, 75.0, 93.0, 82.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 104.0, 83.0, 84.0, 83.0, 96.0, 87.0, 83.0, 101.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 117.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 151.0, 152.0, 153.0, 151.0, 149.0, 150.0, 150.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 55.0, 60.0, 59.0, 55.0, 54.0, 54.0, 53.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0, 57.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 81.0, 81.0, 83.0, 82.0, 83.0, 80.0, 82.0, 82.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 131.0, 135.0, 134.0, 130.0, 131.0, 132.0, 176.0, 130.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 194.0, 183.0, 188.0, 180.0, 189.0, 189.0, 192.0, 182.0, 184.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 72.0, 72.0, 71.0, 70.0, 74.0, 69.0, 71.0, 70.0], "value": 71.3, "unit": "score"}]}]} 16:26:56 INFO - Return code: 0 16:26:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:26:56 INFO - # TBPL SUCCESS # 16:26:56 INFO - Running post-action listener: _resource_record_post_action 16:26:56 INFO - [mozharness: 2017-05-09 23:26:56.348659Z] Finished run-tests step (success) 16:26:56 INFO - Running post-run listener: _resource_record_post_run 16:26:56 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8007680; Write bytes: 375865344; Read time: 1760; Write time: 266620 16:26:56 INFO - TinderboxPrint: CPU usage
13.3% 16:26:56 INFO - TinderboxPrint: I/O read bytes / time
8,007,680 / 1,760 16:26:56 INFO - TinderboxPrint: I/O write bytes / time
375,865,344 / 266,620 16:26:56 INFO - TinderboxPrint: CPU idle
8,302.1 (86.7%) 16:26:56 INFO - TinderboxPrint: CPU user
1,217.3 (12.7%) 16:26:56 INFO - TinderboxPrint: Swap in / out
0 / 0 16:26:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 119316480; Read time: 0; Write time: 125700 16:26:56 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 5795840; Write bytes: 256081920; Read time: 1604; Write time: 140896 16:26:56 INFO - Running post-run listener: _upload_blobber_files 16:26:56 INFO - Blob upload gear active. 16:26:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:26:56 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 16:26:56 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'] 16:26:56 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 16:26:57 INFO - (blobuploader) - INFO - Open directory for files ... 16:26:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:26:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:26:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:58 INFO - (blobuploader) - INFO - Done attempting. 16:26:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:26:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:27:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:27:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:27:01 INFO - (blobuploader) - INFO - Done attempting. 16:27:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:27:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:27:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:27:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:27:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:27:02 INFO - (blobuploader) - INFO - Done attempting. 16:27:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:27:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:27:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:27:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:27:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:27:03 INFO - (blobuploader) - INFO - Done attempting. 16:27:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:27:03 INFO - Return code: 0 16:27:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:27:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:27:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d59ca6f7ea818d35f8707b877803c6e91bd74617746a760a71795fb9d18f2ca433339f7cc3a4fd2a56577677e234749df7ec5250bdb6c8d62d2fda528d364d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573af527d6ba9622c9d2a69ba9ffaec0fbd1eff9a8c12571439bd7fd0fdbe3943c23f0ae597f8f1b7a74ac6f3fa69089ce3687c4b1573dc5500c45f70e800632", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bee0fea7f6bb7d36aec6b457873b4f369fe528e7fc686df751c0cceec7121da20c4dc2fe87777738a8c7a64f7a30588276d468ada37d91229d3da5dc62a773e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/085a6c1b5483f8e1686b5eaae4bbb6c903e9d9b9c77d2dbab8ced7eabf32ca20682044535c7186992f546bdf0651a3e62d8c96e060eacd0a2aa623b693101ed1"} 16:27:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:27:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:27:03 INFO - Contents: 16:27:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d59ca6f7ea818d35f8707b877803c6e91bd74617746a760a71795fb9d18f2ca433339f7cc3a4fd2a56577677e234749df7ec5250bdb6c8d62d2fda528d364d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573af527d6ba9622c9d2a69ba9ffaec0fbd1eff9a8c12571439bd7fd0fdbe3943c23f0ae597f8f1b7a74ac6f3fa69089ce3687c4b1573dc5500c45f70e800632", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bee0fea7f6bb7d36aec6b457873b4f369fe528e7fc686df751c0cceec7121da20c4dc2fe87777738a8c7a64f7a30588276d468ada37d91229d3da5dc62a773e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/085a6c1b5483f8e1686b5eaae4bbb6c903e9d9b9c77d2dbab8ced7eabf32ca20682044535c7186992f546bdf0651a3e62d8c96e060eacd0a2aa623b693101ed1"} 16:27:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:27:03 INFO - Copying logs to upload dir... 16:27:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:27:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.489796 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-05-09 16:27:03.224494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 16:27:03.228795) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972 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/c4d59ca6f7ea818d35f8707b877803c6e91bd74617746a760a71795fb9d18f2ca433339f7cc3a4fd2a56577677e234749df7ec5250bdb6c8d62d2fda528d364d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573af527d6ba9622c9d2a69ba9ffaec0fbd1eff9a8c12571439bd7fd0fdbe3943c23f0ae597f8f1b7a74ac6f3fa69089ce3687c4b1573dc5500c45f70e800632", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bee0fea7f6bb7d36aec6b457873b4f369fe528e7fc686df751c0cceec7121da20c4dc2fe87777738a8c7a64f7a30588276d468ada37d91229d3da5dc62a773e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/085a6c1b5483f8e1686b5eaae4bbb6c903e9d9b9c77d2dbab8ced7eabf32ca20682044535c7186992f546bdf0651a3e62d8c96e060eacd0a2aa623b693101ed1"} build_url:https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011202 build_url: 'https://queue.taskcluster.net/v1/task/YZH8xTPhQjKWLEHyL1kmMg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d59ca6f7ea818d35f8707b877803c6e91bd74617746a760a71795fb9d18f2ca433339f7cc3a4fd2a56577677e234749df7ec5250bdb6c8d62d2fda528d364d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573af527d6ba9622c9d2a69ba9ffaec0fbd1eff9a8c12571439bd7fd0fdbe3943c23f0ae597f8f1b7a74ac6f3fa69089ce3687c4b1573dc5500c45f70e800632", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bee0fea7f6bb7d36aec6b457873b4f369fe528e7fc686df751c0cceec7121da20c4dc2fe87777738a8c7a64f7a30588276d468ada37d91229d3da5dc62a773e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/085a6c1b5483f8e1686b5eaae4bbb6c903e9d9b9c77d2dbab8ced7eabf32ca20682044535c7186992f546bdf0651a3e62d8c96e060eacd0a2aa623b693101ed1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 16:27:03.257036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:27:03.257340) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1494371162.182990-1521624972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004387 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:27:03.337177) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 16:27:03.340503) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 16:27:03.340789) ========= ========= Total master_lag: 0.21 =========