builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-035 starttime: 1487206383.17 results: success (0) revision: e9b926463f9ea76b836ebecd824506e1febee19e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.169955) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.170444) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.170715) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.187099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.187446) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-15 16:53: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.5M=0s 2017-02-15 16:53:03 (49.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115691 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.316427) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.316742) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014811 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:03.369414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 16:53:03.369751) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e9b926463f9ea76b836ebecd824506e1febee19e --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 e9b926463f9ea76b836ebecd824506e1febee19e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-15 16:53:03,439 truncating revision to first 12 chars 2017-02-15 16:53:03,439 Setting DEBUG logging. 2017-02-15 16:53:03,439 attempt 1/10 2017-02-15 16:53:03,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e9b926463f9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-15 16:53:04,737 unpacking tar archive at: mozilla-central-e9b926463f9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.634808 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 16:53:05.017911) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:05.018237) ========= script_repo_revision: e9b926463f9ea76b836ebecd824506e1febee19e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:05.018630) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:05.019004) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 16:53:05.028439) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-15 16:53:05.028810) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:53:05 INFO - MultiFileLogger online at 20170215 16:53:05 in /builds/slave/test 16:53:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:53:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:53:05 INFO - {'append_to_log': False, 16:53:05 INFO - 'base_work_dir': '/builds/slave/test', 16:53:05 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:53:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:53:05 INFO - 'branch': 'Firefox-Non-PGO', 16:53:05 INFO - 'buildbot_json_path': 'buildprops.json', 16:53:05 INFO - 'config_files': ('talos/linux_config.py',), 16:53:05 INFO - 'default_actions': ('clobber', 16:53:05 INFO - 'read-buildbot-config', 16:53:05 INFO - 'download-and-extract', 16:53:05 INFO - 'populate-webroot', 16:53:05 INFO - 'create-virtualenv', 16:53:05 INFO - 'install', 16:53:05 INFO - 'run-tests'), 16:53:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:53:05 INFO - 'download_minidump_stackwalk': True, 16:53:05 INFO - 'download_symbols': 'ondemand', 16:53:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:53:05 INFO - 'tooltool.py': '/tools/tooltool.py', 16:53:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:53:05 INFO - '/tools/misc-python/virtualenv.py')}, 16:53:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:53:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:53:05 INFO - 'installer_path': 'installer.exe', 16:53:05 INFO - 'log_level': 'info', 16:53:05 INFO - 'log_name': 'talos', 16:53:05 INFO - 'log_to_console': True, 16:53:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:53:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:53:05 INFO - 'opt_config_files': (), 16:53:05 INFO - 'pip_index': False, 16:53:05 INFO - 'sps_profile': False, 16:53:05 INFO - 'sps_profile_interval': 0, 16:53:05 INFO - 'suite': 'dromaeojs', 16:53:05 INFO - 'system_bits': '32', 16:53:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:53:05 INFO - 'title': 'talos-linux64-ix-035', 16:53:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:53:05 INFO - 'use_talos_json': True, 16:53:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:53:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:53:05 INFO - 'work_dir': 'build'} 16:53:05 INFO - [mozharness: 2017-02-16 00:53:05.196863Z] Running clobber step. 16:53:05 INFO - Running pre-action listener: _resource_record_pre_action 16:53:05 INFO - Running main action method: clobber 16:53:05 INFO - rmtree: /builds/slave/test/build 16:53:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:53:06 INFO - Running post-action listener: _resource_record_post_action 16:53:06 INFO - [mozharness: 2017-02-16 00:53:06.660069Z] Finished clobber step (success) 16:53:06 INFO - [mozharness: 2017-02-16 00:53:06.660169Z] Running read-buildbot-config step. 16:53:06 INFO - Running pre-action listener: _resource_record_pre_action 16:53:06 INFO - Running main action method: read_buildbot_config 16:53:06 INFO - Using buildbot properties: 16:53:06 INFO - { 16:53:06 INFO - "project": "", 16:53:06 INFO - "product": "firefox", 16:53:06 INFO - "who": "kwierso@gmail.com", 16:53:06 INFO - "installer_path": "public/build/target.tar.bz2", 16:53:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:53:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:53:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:53:06 INFO - "stage_platform": "linux64", 16:53:06 INFO - "basedir": "/builds/slave/test", 16:53:06 INFO - "buildnumber": 983, 16:53:06 INFO - "platform": "ubuntu64_hw", 16:53:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:53:06 INFO - "slavebuilddir": "test", 16:53:06 INFO - "taskId": "LhneD5WZTbafkKlog4hM2Q", 16:53:06 INFO - "branch": "mozilla-central", 16:53:06 INFO - "script_repo_revision": "production", 16:53:06 INFO - "revision": "e9b926463f9ea76b836ebecd824506e1febee19e", 16:53:06 INFO - "slavename": "talos-linux64-ix-035", 16:53:06 INFO - "repo_path": "mozilla-central" 16:53:06 INFO - } 16:53:06 INFO - Finding installer, test and symbols from parent task. 16:53:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LhneD5WZTbafkKlog4hM2Q'}, attempt #1 16:53:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LhneD5WZTbafkKlog4hM2Q'}, attempt #1 16:53:07 INFO - Task dependencies: QU5xlODFTFukOLooyHFytA 16:53:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA'}, attempt #1 16:53:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2 16:53:08 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/test_packages.json 16:53:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.crashreporter-symbols.zip 16:53:08 INFO - Running post-action listener: _resource_record_post_action 16:53:08 INFO - [mozharness: 2017-02-16 00:53:08.128822Z] Finished read-buildbot-config step (success) 16:53:08 INFO - [mozharness: 2017-02-16 00:53:08.129038Z] Running download-and-extract step. 16:53:08 INFO - Running pre-action listener: _resource_record_pre_action 16:53:08 INFO - Running main action method: download_and_extract 16:53:08 INFO - mkdir: /builds/slave/test/build/tests 16:53:08 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:53:08 INFO - trying https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.test_packages.json 16:53:08 INFO - Downloading https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:53:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:53:08 INFO - Downloaded 896 bytes. 16:53:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:53:08 INFO - Using the following test package requirements: 16:53:08 INFO - {u'common': [u'target.common.tests.zip'], 16:53:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:53:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:53:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:53:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:53:08 INFO - u'mozbase': [u'target.common.tests.zip'], 16:53:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:53:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:53:08 INFO - u'web-platform': [u'target.common.tests.zip', 16:53:08 INFO - u'target.web-platform.tests.zip'], 16:53:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:53:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:53:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.common.tests.zip 16:53:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:53:08 INFO - Fetch https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.common.tests.zip into memory 16:53:09 INFO - Expected file size: 57026157 16:53:09 INFO - Obtained file size: 57026157 16:53:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:53:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.common.tests.zip 16:53:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:53:19 INFO - Fetch https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.common.tests.zip into memory 16:53:20 INFO - Expected file size: 57026157 16:53:20 INFO - Obtained file size: 57026157 16:53:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.talos.tests.zip 16:53:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:53:31 INFO - Fetch https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.talos.tests.zip into memory 16:53:32 INFO - Expected file size: 14403647 16:53:32 INFO - Obtained file size: 14403647 16:53: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:53:32 INFO - trying https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2 16:53:32 INFO - Downloading https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:53:33 INFO - Downloaded 56406754 bytes. 16:53:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2 16:53:33 INFO - mkdir: /builds/slave/test/properties 16:53:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:53:33 INFO - Writing to file /builds/slave/test/properties/build_url 16:53:33 INFO - Contents: 16:53:33 INFO - build_url:https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2 16:53:33 INFO - Running post-action listener: _resource_record_post_action 16:53:33 INFO - Running post-action listener: set_extra_try_arguments 16:53:33 INFO - [mozharness: 2017-02-16 00:53:33.429205Z] Finished download-and-extract step (success) 16:53:33 INFO - [mozharness: 2017-02-16 00:53:33.429301Z] Running populate-webroot step. 16:53:33 INFO - Running pre-action listener: _resource_record_pre_action 16:53:33 INFO - Running main action method: populate_webroot 16:53:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:53:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:53:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:53:33 INFO - '--tppagecycles', 16:53:33 INFO - '7'], 16:53:33 INFO - 'tests': ['tsvgm']}}, 16:53:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:53:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:53:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:53:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:53:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['damp', 'tps']}, 16:53:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'tests': ['damp', 'tps']}, 16:53:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['dromaeo_dom']}, 16:53:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:53:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['basic_compositor_video']}, 16:53:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:53:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['a11yr', 16:53:33 INFO - 'ts_paint', 16:53:33 INFO - 'tpaint', 16:53:33 INFO - 'sessionrestore', 16:53:33 INFO - 'sessionrestore_no_auto_restore', 16:53:33 INFO - 'tabpaint']}, 16:53:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:53:33 INFO - 'ts_paint', 16:53:33 INFO - 'tpaint', 16:53:33 INFO - 'sessionrestore', 16:53:33 INFO - 'sessionrestore_no_auto_restore', 16:53:33 INFO - 'tabpaint']}, 16:53:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:53:33 INFO - 'ts_paint', 16:53:33 INFO - 'tpaint', 16:53:33 INFO - 'sessionrestore', 16:53:33 INFO - 'sessionrestore_no_auto_restore', 16:53:33 INFO - 'tabpaint']}, 16:53:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:53:33 INFO - 'ts_paint', 16:53:33 INFO - 'tpaint', 16:53:33 INFO - 'sessionrestore', 16:53:33 INFO - 'sessionrestore_no_auto_restore', 16:53:33 INFO - 'tabpaint']}, 16:53:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['a11yr', 16:53:33 INFO - 'ts_paint', 16:53:33 INFO - 'tpaint', 16:53:33 INFO - 'sessionrestore', 16:53:33 INFO - 'sessionrestore_no_auto_restore', 16:53:33 INFO - 'tabpaint']}, 16:53:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['a11yr', 16:53:33 INFO - 'ts_paint', 16:53:33 INFO - 'tpaint', 16:53:33 INFO - 'sessionrestore', 16:53:33 INFO - 'sessionrestore_no_auto_restore', 16:53:33 INFO - 'tabpaint']}, 16:53:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['tsvgx', 16:53:33 INFO - 'tsvgr_opacity', 16:53:33 INFO - 'tart', 16:53:33 INFO - 'tscrollx', 16:53:33 INFO - 'cart', 16:53:33 INFO - 'tsvg_static']}, 16:53:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:53:33 INFO - 'tsvgr_opacity', 16:53:33 INFO - 'tart', 16:53:33 INFO - 'tscrollx', 16:53:33 INFO - 'cart', 16:53:33 INFO - 'tsvg_static']}, 16:53:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'talos_options': ['--disable-e10s'], 16:53:33 INFO - 'tests': ['tp5o']}, 16:53:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:53:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'talos_options': ['--disable-e10s', 16:53:33 INFO - '--xperf_path', 16:53:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:33 INFO - 'tests': ['tp5n']}, 16:53:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:53:33 INFO - 'talos_options': ['--xperf_path', 16:53:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:33 INFO - 'tests': ['tp5n']}}, 16:53:33 INFO - 'talos.zip': {'path': '', 16:53:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:53:33 INFO - Running post-action listener: _resource_record_post_action 16:53:33 INFO - [mozharness: 2017-02-16 00:53:33.442543Z] Finished populate-webroot step (success) 16:53:33 INFO - [mozharness: 2017-02-16 00:53:33.442637Z] Running create-virtualenv step. 16:53:33 INFO - Running pre-action listener: _resource_record_pre_action 16:53:33 INFO - Running main action method: create_virtualenv 16:53:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:53: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:53:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:53:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:53:33 INFO - Using real prefix '/usr' 16:53:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:53:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:53:36 INFO - Installing pip.................done. 16:53:36 INFO - Return code: 0 16:53:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:53:36 INFO - Reading from file tmpfile_stdout 16:53:36 INFO - Output received: 16:53: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:53:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:53: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:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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:53: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:53:36 INFO - Using env: {'DISPLAY': ':0', 16:53:36 INFO - 'HOME': '/home/cltbld', 16:53:36 INFO - 'LANG': 'en_US.UTF-8', 16:53:36 INFO - 'LANGUAGE': 'en_US:en', 16:53:36 INFO - 'LOGNAME': 'cltbld', 16:53:36 INFO - 'MAIL': '/var/mail/cltbld', 16:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:36 INFO - 'MOZ_NO_REMOTE': '1', 16:53:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:36 INFO - 'NO_EM_RESTART': '1', 16:53:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:36 INFO - 'PWD': '/builds/slave/test', 16:53:36 INFO - 'SHELL': '/bin/bash', 16:53:36 INFO - 'SHLVL': '1', 16:53:36 INFO - 'TERM': 'linux', 16:53:36 INFO - 'TMOUT': '86400', 16:53:36 INFO - 'USER': 'cltbld', 16:53:36 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:36 INFO - '_': '/tools/buildbot/bin/python'} 16:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53: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:53:36 INFO - Cleaning up... 16:53:36 INFO - Return code: 0 16:53:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:53: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:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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:53: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:53:36 INFO - Using env: {'DISPLAY': ':0', 16:53:36 INFO - 'HOME': '/home/cltbld', 16:53:36 INFO - 'LANG': 'en_US.UTF-8', 16:53:36 INFO - 'LANGUAGE': 'en_US:en', 16:53:36 INFO - 'LOGNAME': 'cltbld', 16:53:36 INFO - 'MAIL': '/var/mail/cltbld', 16:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:36 INFO - 'MOZ_NO_REMOTE': '1', 16:53:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:36 INFO - 'NO_EM_RESTART': '1', 16:53:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:36 INFO - 'PWD': '/builds/slave/test', 16:53:36 INFO - 'SHELL': '/bin/bash', 16:53:36 INFO - 'SHLVL': '1', 16:53:36 INFO - 'TERM': 'linux', 16:53:36 INFO - 'TMOUT': '86400', 16:53:36 INFO - 'USER': 'cltbld', 16:53:36 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:36 INFO - '_': '/tools/buildbot/bin/python'} 16:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:36 INFO - Downloading/unpacking psutil>=3.1.1 16:53:36 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:53:36 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:53:36 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:53:36 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:53:36 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:53:36 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:53:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:53:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:53:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:53:38 INFO - Installing collected packages: psutil 16:53:38 INFO - Running setup.py install for psutil 16:53:38 INFO - building 'psutil._psutil_linux' extension 16:53:38 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:53:39 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:53:39 INFO - building 'psutil._psutil_posix' extension 16:53:39 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:53:39 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:53:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:53:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:53:39 INFO - Successfully installed psutil 16:53:39 INFO - Cleaning up... 16:53:39 INFO - Return code: 0 16:53:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:39 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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53:39 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:53:39 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:53:39 INFO - Using env: {'DISPLAY': ':0', 16:53:39 INFO - 'HOME': '/home/cltbld', 16:53:39 INFO - 'LANG': 'en_US.UTF-8', 16:53:39 INFO - 'LANGUAGE': 'en_US:en', 16:53:39 INFO - 'LOGNAME': 'cltbld', 16:53:39 INFO - 'MAIL': '/var/mail/cltbld', 16:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:39 INFO - 'MOZ_NO_REMOTE': '1', 16:53:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:39 INFO - 'NO_EM_RESTART': '1', 16:53:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:39 INFO - 'PWD': '/builds/slave/test', 16:53:39 INFO - 'SHELL': '/bin/bash', 16:53:39 INFO - 'SHLVL': '1', 16:53:39 INFO - 'TERM': 'linux', 16:53:39 INFO - 'TMOUT': '86400', 16:53:39 INFO - 'USER': 'cltbld', 16:53:39 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:39 INFO - '_': '/tools/buildbot/bin/python'} 16:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:53:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:53:41 INFO - Installing collected packages: mozsystemmonitor 16:53:41 INFO - Running setup.py install for mozsystemmonitor 16:53:41 INFO - Successfully installed mozsystemmonitor 16:53:41 INFO - Cleaning up... 16:53:41 INFO - Return code: 0 16:53:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:53:41 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:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:41 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:53:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:41 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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53:41 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:53:41 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:53:41 INFO - Using env: {'DISPLAY': ':0', 16:53:41 INFO - 'HOME': '/home/cltbld', 16:53:41 INFO - 'LANG': 'en_US.UTF-8', 16:53:41 INFO - 'LANGUAGE': 'en_US:en', 16:53:41 INFO - 'LOGNAME': 'cltbld', 16:53:41 INFO - 'MAIL': '/var/mail/cltbld', 16:53:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:41 INFO - 'MOZ_NO_REMOTE': '1', 16:53:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:41 INFO - 'NO_EM_RESTART': '1', 16:53:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:41 INFO - 'PWD': '/builds/slave/test', 16:53:41 INFO - 'SHELL': '/bin/bash', 16:53:41 INFO - 'SHLVL': '1', 16:53:41 INFO - 'TERM': 'linux', 16:53:41 INFO - 'TMOUT': '86400', 16:53:41 INFO - 'USER': 'cltbld', 16:53:41 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:41 INFO - '_': '/tools/buildbot/bin/python'} 16:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:42 INFO - Downloading/unpacking jsonschema==2.5.1 16:53: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:53: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:53: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:53: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:53: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:53: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:53:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:53:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:53: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:53: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:53: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:53: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:53: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:53: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:53:44 INFO - Downloading functools32-3.2.3-2.tar.gz 16:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:53:44 INFO - warning: no files found matching '*.txt' 16:53:44 INFO - no previously-included directories found matching 'build' 16:53:44 INFO - no previously-included directories found matching 'dist' 16:53:44 INFO - no previously-included directories found matching '.git*' 16:53:44 INFO - Installing collected packages: jsonschema, functools32 16:53:44 INFO - Running setup.py install for functools32 16:53:44 INFO - warning: no files found matching '*.txt' 16:53:44 INFO - no previously-included directories found matching 'build' 16:53:44 INFO - no previously-included directories found matching 'dist' 16:53:44 INFO - no previously-included directories found matching '.git*' 16:53:44 INFO - Successfully installed jsonschema functools32 16:53:44 INFO - Cleaning up... 16:53:44 INFO - Return code: 0 16:53:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:53: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:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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', '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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:53: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 functools32==3.2.3-2 16:53:44 INFO - Using env: {'DISPLAY': ':0', 16:53:44 INFO - 'HOME': '/home/cltbld', 16:53:44 INFO - 'LANG': 'en_US.UTF-8', 16:53:44 INFO - 'LANGUAGE': 'en_US:en', 16:53:44 INFO - 'LOGNAME': 'cltbld', 16:53:44 INFO - 'MAIL': '/var/mail/cltbld', 16:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:44 INFO - 'MOZ_NO_REMOTE': '1', 16:53:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:44 INFO - 'NO_EM_RESTART': '1', 16:53:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:44 INFO - 'PWD': '/builds/slave/test', 16:53:44 INFO - 'SHELL': '/bin/bash', 16:53:44 INFO - 'SHLVL': '1', 16:53:44 INFO - 'TERM': 'linux', 16:53:44 INFO - 'TMOUT': '86400', 16:53:44 INFO - 'USER': 'cltbld', 16:53:44 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:44 INFO - '_': '/tools/buildbot/bin/python'} 16:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:53:44 INFO - Cleaning up... 16:53:44 INFO - Return code: 0 16:53:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:53: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:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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', '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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:53: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 blobuploader==1.2.4 16:53:44 INFO - Using env: {'DISPLAY': ':0', 16:53:44 INFO - 'HOME': '/home/cltbld', 16:53:44 INFO - 'LANG': 'en_US.UTF-8', 16:53:44 INFO - 'LANGUAGE': 'en_US:en', 16:53:44 INFO - 'LOGNAME': 'cltbld', 16:53:44 INFO - 'MAIL': '/var/mail/cltbld', 16:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:44 INFO - 'MOZ_NO_REMOTE': '1', 16:53:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:44 INFO - 'NO_EM_RESTART': '1', 16:53:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:44 INFO - 'PWD': '/builds/slave/test', 16:53:44 INFO - 'SHELL': '/bin/bash', 16:53:44 INFO - 'SHLVL': '1', 16:53:44 INFO - 'TERM': 'linux', 16:53:44 INFO - 'TMOUT': '86400', 16:53:44 INFO - 'USER': 'cltbld', 16:53:44 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:44 INFO - '_': '/tools/buildbot/bin/python'} 16:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:53: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:53: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:53: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:53: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:53: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:53: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:53:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:53:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:53: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:53: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:53: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:53: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:53: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:53: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:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:53:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:53: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:53: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:53: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:53: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:53: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:53: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:53:47 INFO - Downloading docopt-0.6.1.tar.gz 16:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:53:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:53:47 INFO - Running setup.py install for blobuploader 16:53:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:53:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:53:47 INFO - Running setup.py install for requests 16:53:48 INFO - Running setup.py install for docopt 16:53:48 INFO - Successfully installed blobuploader requests docopt 16:53:48 INFO - Cleaning up... 16:53:48 INFO - Return code: 0 16:53:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53: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:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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:53: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:53:48 INFO - Using env: {'DISPLAY': ':0', 16:53:48 INFO - 'HOME': '/home/cltbld', 16:53:48 INFO - 'LANG': 'en_US.UTF-8', 16:53:48 INFO - 'LANGUAGE': 'en_US:en', 16:53:48 INFO - 'LOGNAME': 'cltbld', 16:53:48 INFO - 'MAIL': '/var/mail/cltbld', 16:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:48 INFO - 'MOZ_NO_REMOTE': '1', 16:53:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:48 INFO - 'NO_EM_RESTART': '1', 16:53:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:48 INFO - 'PWD': '/builds/slave/test', 16:53:48 INFO - 'SHELL': '/bin/bash', 16:53:48 INFO - 'SHLVL': '1', 16:53:48 INFO - 'TERM': 'linux', 16:53:48 INFO - 'TMOUT': '86400', 16:53:48 INFO - 'USER': 'cltbld', 16:53:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:48 INFO - '_': '/tools/buildbot/bin/python'} 16:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:53:48 INFO - Running setup.py (path:/tmp/pip-XzweAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:53:48 INFO - Running setup.py (path:/tmp/pip-sug21R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:53:48 INFO - Running setup.py (path:/tmp/pip-bl8ILX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:53:48 INFO - Running setup.py (path:/tmp/pip-voWzUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:53:48 INFO - Running setup.py (path:/tmp/pip-40n_2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:53:48 INFO - Running setup.py (path:/tmp/pip-yyIgTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:53:49 INFO - Running setup.py (path:/tmp/pip-a2fLnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:53:49 INFO - Running setup.py (path:/tmp/pip-P90ZKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:53:49 INFO - Running setup.py (path:/tmp/pip-9mlQ2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:53:49 INFO - Running setup.py (path:/tmp/pip-hPKHpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:53:49 INFO - Running setup.py (path:/tmp/pip-5SmDat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:53:49 INFO - Running setup.py (path:/tmp/pip-8hdycQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:53:49 INFO - Running setup.py (path:/tmp/pip-jJlNCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:53:49 INFO - Running setup.py (path:/tmp/pip-VFz2HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:49 INFO - Running setup.py (path:/tmp/pip-vPI3Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:53:49 INFO - Running setup.py (path:/tmp/pip-lFynFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:53:49 INFO - Running setup.py (path:/tmp/pip-_mb3v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:53:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:53:49 INFO - Running setup.py install for manifestparser 16:53:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:53:49 INFO - Running setup.py install for mozcrash 16:53:50 INFO - Running setup.py install for mozdebug 16:53:50 INFO - Running setup.py install for mozdevice 16:53:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Running setup.py install for mozfile 16:53:50 INFO - Running setup.py install for mozhttpd 16:53:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Running setup.py install for mozinfo 16:53:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Running setup.py install for mozInstall 16:53:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Running setup.py install for mozleak 16:53:50 INFO - Running setup.py install for mozlog 16:53:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Running setup.py install for moznetwork 16:53:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:53:50 INFO - Running setup.py install for mozprocess 16:53:51 INFO - Running setup.py install for mozprofile 16:53:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:53:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:53:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:53:51 INFO - Running setup.py install for mozrunner 16:53:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:53:51 INFO - Running setup.py install for mozscreenshot 16:53:51 INFO - Running setup.py install for moztest 16:53:51 INFO - Running setup.py install for mozversion 16:53:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:53:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:53:51 INFO - Cleaning up... 16:53:51 INFO - Return code: 0 16:53:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53: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:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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:53: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:53:51 INFO - Using env: {'DISPLAY': ':0', 16:53:51 INFO - 'HOME': '/home/cltbld', 16:53:51 INFO - 'LANG': 'en_US.UTF-8', 16:53:51 INFO - 'LANGUAGE': 'en_US:en', 16:53:51 INFO - 'LOGNAME': 'cltbld', 16:53:51 INFO - 'MAIL': '/var/mail/cltbld', 16:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:51 INFO - 'MOZ_NO_REMOTE': '1', 16:53:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:51 INFO - 'NO_EM_RESTART': '1', 16:53:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:51 INFO - 'PWD': '/builds/slave/test', 16:53:51 INFO - 'SHELL': '/bin/bash', 16:53:51 INFO - 'SHLVL': '1', 16:53:51 INFO - 'TERM': 'linux', 16:53:51 INFO - 'TMOUT': '86400', 16:53:51 INFO - 'USER': 'cltbld', 16:53:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:51 INFO - '_': '/tools/buildbot/bin/python'} 16:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:53:51 INFO - Running setup.py (path:/tmp/pip-ACpAT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:53:51 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:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:53:51 INFO - Running setup.py (path:/tmp/pip-9WBQHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:53:52 INFO - Running setup.py (path:/tmp/pip-8Y3Qup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:53:52 INFO - Running setup.py (path:/tmp/pip-txvnkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:53:52 INFO - Running setup.py (path:/tmp/pip-ZlrcUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:53:52 INFO - Running setup.py (path:/tmp/pip-2Kgg25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:53:52 INFO - Running setup.py (path:/tmp/pip-APcruL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:53:52 INFO - Running setup.py (path:/tmp/pip-qxSjHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:53:52 INFO - Running setup.py (path:/tmp/pip-h4uePP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:53:52 INFO - Running setup.py (path:/tmp/pip-wLf1Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:53:52 INFO - Running setup.py (path:/tmp/pip-2AU7gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:53: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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:53:52 INFO - Running setup.py (path:/tmp/pip-v2G0ES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:53:52 INFO - Running setup.py (path:/tmp/pip-cx_N5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:53:52 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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:53:52 INFO - Running setup.py (path:/tmp/pip-5PWfPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:53:52 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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:52 INFO - Running setup.py (path:/tmp/pip-0e0FMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:52 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:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:53:52 INFO - Running setup.py (path:/tmp/pip-PL_t3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:53: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:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:53:53 INFO - Running setup.py (path:/tmp/pip-KwQUz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:53: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:53: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:53: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:53: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:53: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:53: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:53:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:53: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:53: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:53: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:53: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:53: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:53: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:53:55 INFO - Downloading blessings-1.6.tar.gz 16:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:53:55 INFO - Installing collected packages: blessings 16:53:55 INFO - Running setup.py install for blessings 16:53:55 INFO - Successfully installed blessings 16:53:55 INFO - Cleaning up... 16:53:55 INFO - Return code: 0 16:53:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:53:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:53:55 INFO - Reading from file tmpfile_stdout 16:53:55 INFO - Current package versions: 16:53:55 INFO - argparse == 1.2.1 16:53:55 INFO - blessings == 1.6 16:53:55 INFO - blobuploader == 1.2.4 16:53:55 INFO - docopt == 0.6.1 16:53:55 INFO - functools32 == 3.2.3-2 16:53:55 INFO - jsonschema == 2.5.1 16:53:55 INFO - manifestparser == 1.1 16:53:55 INFO - mozInstall == 1.12 16:53:55 INFO - mozcrash == 1.0 16:53:55 INFO - mozdebug == 0.1 16:53:55 INFO - mozdevice == 0.49 16:53:55 INFO - mozfile == 1.2 16:53:55 INFO - mozhttpd == 0.7 16:53:55 INFO - mozinfo == 0.9 16:53:55 INFO - mozleak == 0.1 16:53:55 INFO - mozlog == 3.4 16:53:55 INFO - moznetwork == 0.27 16:53:55 INFO - mozprocess == 0.24 16:53:55 INFO - mozprofile == 0.28 16:53:55 INFO - mozrunner == 6.13 16:53:55 INFO - mozscreenshot == 0.1 16:53:55 INFO - mozsystemmonitor == 0.3 16:53:55 INFO - moztest == 0.8 16:53:55 INFO - mozversion == 1.4 16:53:55 INFO - psutil == 3.1.1 16:53:55 INFO - requests == 1.2.3 16:53:55 INFO - wsgiref == 0.1.2 16:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53: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:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:55 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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53:55 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:53:55 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:53:55 INFO - Using env: {'DISPLAY': ':0', 16:53:55 INFO - 'HOME': '/home/cltbld', 16:53:55 INFO - 'LANG': 'en_US.UTF-8', 16:53:55 INFO - 'LANGUAGE': 'en_US:en', 16:53:55 INFO - 'LOGNAME': 'cltbld', 16:53:55 INFO - 'MAIL': '/var/mail/cltbld', 16:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:55 INFO - 'MOZ_NO_REMOTE': '1', 16:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:55 INFO - 'NO_EM_RESTART': '1', 16:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:55 INFO - 'PWD': '/builds/slave/test', 16:53:55 INFO - 'SHELL': '/bin/bash', 16:53:55 INFO - 'SHLVL': '1', 16:53:55 INFO - 'TERM': 'linux', 16:53:55 INFO - 'TMOUT': '86400', 16:53:55 INFO - 'USER': 'cltbld', 16:53:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:55 INFO - '_': '/tools/buildbot/bin/python'} 16:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53: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:53: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:53: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:53: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:53: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:53: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:53: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:53: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:53: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:53: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:53: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:53:55 INFO - Cleaning up... 16:53:55 INFO - Return code: 0 16:53:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:53: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:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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 0x14c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1506a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1767310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', '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:53: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:53: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:53:55 INFO - Using env: {'DISPLAY': ':0', 16:53:55 INFO - 'HOME': '/home/cltbld', 16:53:55 INFO - 'LANG': 'en_US.UTF-8', 16:53:55 INFO - 'LANGUAGE': 'en_US:en', 16:53:55 INFO - 'LOGNAME': 'cltbld', 16:53:55 INFO - 'MAIL': '/var/mail/cltbld', 16:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:55 INFO - 'MOZ_NO_REMOTE': '1', 16:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:53:55 INFO - 'NO_EM_RESTART': '1', 16:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:55 INFO - 'PWD': '/builds/slave/test', 16:53:55 INFO - 'SHELL': '/bin/bash', 16:53:55 INFO - 'SHLVL': '1', 16:53:55 INFO - 'TERM': 'linux', 16:53:55 INFO - 'TMOUT': '86400', 16:53:55 INFO - 'USER': 'cltbld', 16:53:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:55 INFO - '_': '/tools/buildbot/bin/python'} 16:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:53:56 INFO - Cleaning up... 16:53:56 INFO - Return code: 0 16:53:56 INFO - Running post-action listener: _resource_record_post_action 16:53:56 INFO - Running post-action listener: _start_resource_monitoring 16:53:56 INFO - Starting resource monitoring. 16:53:56 INFO - [mozharness: 2017-02-16 00:53:56.086030Z] Finished create-virtualenv step (success) 16:53:56 INFO - [mozharness: 2017-02-16 00:53:56.086500Z] Running install step. 16:53:56 INFO - Running pre-action listener: _resource_record_pre_action 16:53:56 INFO - Running main action method: install 16:53:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:53:56 INFO - Reading from file tmpfile_stdout 16:53:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:53:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:53:56 INFO - Reading from file tmpfile_stdout 16:53:56 INFO - Output received: 16:53:56 INFO - Usage: mozinstall [options] installer 16:53:56 INFO - Options: 16:53:56 INFO - -h, --help show this help message and exit 16:53:56 INFO - -d DEST, --destination=DEST 16:53:56 INFO - Directory to install application into. [default: 16:53:56 INFO - "/builds/slave/test"] 16:53:56 INFO - --app=APP Application being installed. [default: firefox] 16:53:56 INFO - mkdir: /builds/slave/test/build/application 16:53:56 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:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:54:12 INFO - Reading from file tmpfile_stdout 16:54:12 INFO - Output received: 16:54:12 INFO - /builds/slave/test/build/application/firefox/firefox 16:54:12 INFO - Running post-action listener: _resource_record_post_action 16:54:12 INFO - [mozharness: 2017-02-16 00:54:12.692149Z] Finished install step (success) 16:54:12 INFO - [mozharness: 2017-02-16 00:54:12.692426Z] Running run-tests step. 16:54:12 INFO - Running pre-action listener: _resource_record_pre_action 16:54:12 INFO - Running main action method: run_tests 16:54:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:54:12 INFO - Python 2.7.3 16:54:12 INFO - Return code: 0 16:54:12 INFO - grabbing minidump binary from tooltool 16:54:12 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:54:12 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 0x1764400>, '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 0x17653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:54:12 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:54:12 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:54:12 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:54:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:54:12 INFO - Return code: 0 16:54:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:54:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:54:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:54:12 INFO - ENV: RUST_BACKTRACE is now 1 16:54:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:54:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:54:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:54:12 INFO - Using env: {'DISPLAY': ':0', 16:54:12 INFO - 'HOME': '/home/cltbld', 16:54:12 INFO - 'LANG': 'en_US.UTF-8', 16:54:12 INFO - 'LANGUAGE': 'en_US:en', 16:54:12 INFO - 'LOGNAME': 'cltbld', 16:54:12 INFO - 'MAIL': '/var/mail/cltbld', 16:54:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:54:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:12 INFO - 'MOZ_NO_REMOTE': '1', 16:54:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:54:12 INFO - 'NO_EM_RESTART': '1', 16:54:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:12 INFO - 'PWD': '/builds/slave/test', 16:54:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:54:12 INFO - 'RUST_BACKTRACE': '1', 16:54:12 INFO - 'SHELL': '/bin/bash', 16:54:12 INFO - 'SHLVL': '1', 16:54:12 INFO - 'TERM': 'linux', 16:54:12 INFO - 'TMOUT': '86400', 16:54:12 INFO - 'USER': 'cltbld', 16:54:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044', 16:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:12 INFO - '_': '/tools/buildbot/bin/python'} 16:54:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:54:12 INFO - mozversion application_buildid: 20170216001840 16:54:12 INFO - mozversion application_changeset: e9b926463f9ea76b836ebecd824506e1febee19e 16:54:12 INFO - mozversion application_display_name: Nightly 16:54:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:54:12 INFO - mozversion application_name: Firefox 16:54:12 INFO - mozversion application_remotingname: firefox 16:54:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:54:12 INFO - mozversion application_vendor: Mozilla 16:54:12 INFO - mozversion application_version: 54.0a1 16:54:12 INFO - mozversion platform_buildid: 20170216001840 16:54:12 INFO - mozversion platform_changeset: e9b926463f9ea76b836ebecd824506e1febee19e 16:54:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:54:12 INFO - mozversion platform_version: 54.0a1 16:54:12 INFO - using testdate: 1487206452 16:54:12 INFO - actual date: 1487206452 16:54:12 INFO - starting webserver on 'localhost:51262' 16:54:12 INFO - SUITE-START | Running 2 tests 16:54:12 INFO - TEST-START | dromaeo_css 16:54:12 INFO - operating with platform_type : linux_ 16:54:12 INFO - Initialising browser for dromaeo_css test... 16:54:12 INFO - TEST-INFO | started process 29098 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGfTSZO/profile http://localhost:51262/getInfo.html) 16:54:16 INFO - PROCESS | 29098 | Iterating over profile /tmp/tmpGfTSZO/profile 16:54:16 INFO - PROCESS | 29098 | __metrics Screen width/height:1600/1200 16:54:16 INFO - PROCESS | 29098 | colorDepth:24 16:54:16 INFO - PROCESS | 29098 | Browser inner width/height: 1024/697 16:54:16 INFO - PROCESS | 29098 | __metrics 16:54:16 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:16 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/key3.db = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/crashes = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/gmp = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/cookies.sqlite = Wed Feb 15 2017 16:54:16 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/extensions.json = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/extensions = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/addons.json = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/extensions.ini = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/safebrowsing = Wed Feb 15 2017 16:54:17 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/cache2 = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/permissions.sqlite = Wed Feb 15 2017 16:54:14 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/pluginreg.dat = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/places.sqlite-wal = Wed Feb 15 2017 16:54:17 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/prefs.js = Wed Feb 15 2017 16:53:32 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | Stat failure 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/.parentlock = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/bookmarkbackups = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/content-prefs.sqlite = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/minidumps = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/thumbnails = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/places.sqlite-shm = Wed Feb 15 2017 16:54:14 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/startupCache = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/secmod.db = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/localstore.rdf = Wed Feb 15 2017 16:53:32 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/places.sqlite = Wed Feb 15 2017 16:54:17 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/directoryLinks.json = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/user.js = Wed Feb 15 2017 16:54:12 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/frequencyCap.json = Wed Feb 15 2017 16:54:15 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/cert8.db = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/mimeTypes.rdf = Wed Feb 15 2017 16:54:16 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/blocklist.xml = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/sessionCheckpoints.json = Wed Feb 15 2017 16:54:16 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | No birth date. Using mtime. 16:54:17 INFO - PROCESS | 29098 | Using date: /tmp/tmpGfTSZO/profile/compatibility.ini = Wed Feb 15 2017 16:54:13 GMT-0800 (PST) 16:54:17 INFO - PROCESS | 29098 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:54:17 INFO - PROCESS | 29098 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:54:19 INFO - TEST-INFO | 29098: exit 0 16:54:19 INFO - Browser initialized. 16:54:19 INFO - Running cycle 1/1 for dromaeo_css test... 16:54:19 INFO - TEST-INFO | started process 29179 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGfTSZO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:54:31 INFO - PROCESS | 29179 | RSS: Main: 153874432 16:54:31 INFO - PROCESS | 29179 | 16:57:37 INFO - PROCESS | 29179 | Cycle 1(1): loaded http://localhost:51262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51262/tests/dromaeo/cssquery-ext.html) 16:57:39 INFO - PROCESS | 29179 | RSS: Main: 275238912 16:57:39 INFO - PROCESS | 29179 | 16:59:20 INFO - PROCESS | 29179 | ************************* 16:59:20 INFO - PROCESS | 29179 | A coding exception was thrown and uncaught in a Task. 16:59:20 INFO - PROCESS | 29179 | 16:59:20 ERROR - PROCESS | 29179 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:59:20 INFO - PROCESS | 29179 | Full stack: 16:59:20 INFO - PROCESS | 29179 | ************************* 17:00:41 INFO - PROCESS | 29179 | Cycle 1(1): loaded http://localhost:51262/tests/dromaeo/cssquery-ext.html (next: http://localhost:51262/tests/dromaeo/cssquery-jquery.html) 17:00:42 INFO - PROCESS | 29179 | RSS: Main: 256831488 17:00:42 INFO - PROCESS | 29179 | 17:03:44 INFO - PROCESS | 29179 | Cycle 1(1): loaded http://localhost:51262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51262/tests/dromaeo/cssquery-mootools.html) 17:03:45 INFO - PROCESS | 29179 | RSS: Main: 193601536 17:03:45 INFO - PROCESS | 29179 | 17:06:47 INFO - PROCESS | 29179 | Cycle 1(1): loaded http://localhost:51262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51262/tests/dromaeo/cssquery-prototype.html) 17:06:48 INFO - PROCESS | 29179 | RSS: Main: 222994432 17:06:48 INFO - PROCESS | 29179 | 17:09:51 INFO - PROCESS | 29179 | Cycle 1(1): loaded http://localhost:51262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51262/tests/dromaeo/cssquery-yui.html) 17:09:52 INFO - PROCESS | 29179 | RSS: Main: 191647744 17:09:52 INFO - PROCESS | 29179 | 17:12:54 INFO - PROCESS | 29179 | Cycle 1(1): loaded http://localhost:51262/tests/dromaeo/cssquery-yui.html (next: http://localhost:51262/tests/dromaeo/cssquery-dojo.html) 17:12:55 INFO - PROCESS | 29179 | RSS: Main: 285171712 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | __start_tp_report 17:12:55 INFO - PROCESS | 29179 | _x_x_mozilla_page_load 17:12:55 INFO - PROCESS | 29179 | _x_x_mozilla_page_load_details 17:12:55 INFO - PROCESS | 29179 | |i|pagename|runs| 17:12:55 INFO - PROCESS | 29179 | |0;dojo.html;2612.947741045179;2630.4081581644145;2657.348949507371;2664.3205982474465;2670.919872403829;4662.766861656921;4743.8814029649275;4756.242568370987;4827.155247831624;4854.296127061579;4721.598664113554;4784.425868895737;4794.592459640935;4834.395700537433;4846.39420072491;4370.781460926941;4399.120175964804;4416.205083085043;4432.867013989569;4522.479914809806;4114.958850411487;4378.124375124988;4414.448193975753;4453.465584129909;4461.843835465759;4335;4351.390805287262;4422.469303683579;4458.1306645204195;4537.069900670371;4400.273954797454;4411.779411029435;4423.336499525065;4436.312371582378;4456.286994080931;4423.690341676084;4469.687121901468;4474.843380481668;4483.058557702886;4485.641148708096;4778.566430070965;4822.252550854623;4838.104950584153;4842.15262329091;4873.853784386474;4950.8267210647455;5176.094183517869;5182.455842136596;5185.0148471790235;5221.2951251580935;5012.749362531858;5073.340465739467;5090.516400941905;5092.541671249568;5093.083245015896;4763.261694437368;4773.403324584427;4782.617390608743;4886.438059623118;4946.35697359341;5470.480304371078;5475.425080366584;5479.726013699299;5481.287432633732;5485.698286594195;4960.404751429852;5001.474845141208;5004.149294619923;5101.158308879068;5189.870253243699;5151.021305951857;5151.407588127413;5151.459096794782;5156.92264616031;5162.225666150108;5101.056304583664;5123.333966584395;5123.718195499283;5124.461931497139;5135.332406787168;5105.795768169306;5106.744662766847;5112.3865136183895;5115.3335752166;5117.8464646060675;4191.371294305878;4216.3464662977285;4256.127493863751;4309.870703878826;4316.978415107905;3685.981570092132;3723;3740.495033170542;3742.0644838790304;3763.5483741950593;4428.070105277928;4433.512313645497;4434.957784920541;4437.844675436344;4477.753723545171;5179.741012949337;5182.948170518322;5192.169252919514;5194.194899790538;5235.3717553893775;4354.651627869763;4398.296272596369;4427.468703755506;4457.04173602677;4475.5748203920575;4568.725876447424;4623.768811559476;4633.30500424939;4637.3275874997935;4645.907081858344;4838.709677419366;4879.316895634614;4881.463039065771;4893.486183950634;4932;4997.3253610762085;5158.59184384806;5176.534111929794;5190.273361729436;5244.816431424882;4789.3055506952;4858.732769697701;4892.975535122301;4938.530839570235;4943.110240156662;5145.511176438233;5185.403678576861;5191.454897235736;5192.896142077062;5200.349956255468;5221.503957124067;5231.686098834082;5235.712035837913;5239.502247286502;5274.393444753902;4828.758562071812;5081.4918508148885;5084.847454576368;5084.949150508448;5091.923621145612;4862.222044472867;4910.410750709938;4988.4263309719845;4990.276409920614;4990.301357809837;4878.243872199815;4898.8775280618265;4900.656954013185;4939.110960027086;4946.3817022872145;4986.351774269322;4987.6508644396;5017.648764586444;5018.172001619692;5046.924296135488;4766.237402015661;4866.367372241728;4890.510948905081;4891.755412229303;4938.654294199371 17:12:55 INFO - PROCESS | 29179 | |1;ext.html;531.208499335989;552.061495457722;562.5780664501624;571.8113022702507;625.0592857749087;25958.740412595867;36525.81737091317;37891.81054094729;38055.42916856249;40746.38880416796;37747.62252377476;38017.04957376067;38081.428778568275;38098;38145.42781858274;6651.368120028614;7559.811004724885;7791.454598178129;7804.180561041094;7804.765857024298;6654.633995130266;9216.815663686722;9481.952590237039;9485.620575176985;9782.804343913118;9710.951445242765;9755.707328780149;9783.266255030865;9784.853227201598;9789.216862651007;9638.518074096302;9704.951475242578;9721.222302215805;9729.61081556744;9730.12428881404;9308.394954327947;9435.056494350578;9455.574499147555;9456.479893605849;9464;36804.44793328102;36986.26027479435;37018.25963480742;37079.25841483155;37173.814130929444;3445.9483107753404;3551.325248202859;3562.946555801639;3586.103474131467;3615.8192090395373;27398;27990;28027.439451211067;28391.432171356664;28443;3302.768806183568;3386.407378708716;3478.4260597001467;3482.8258587070545;3524.541809564766;217052.99999999843;217484;217708;217743;218911;42948.57051429446;44507;51853.481465184865;52687.473125268254;52822.47177528252;55083.940766515654;56385;56408.71795640996;61763;61859.690701546206;13629.114107582975;13723.313834308245;13726.519571814939;13734.793978090336;13746.450141994408;10677.305975111592;10911.181661375269;10911.23621346514;10978.890211097787;10988.395638239814;16101.436449724204;16126.838731612534;16172.919135404247;16213.189340533163;16286.674266514603;5705.115707065412;5728.684922329312;5761.53907687384;5805.158252053513;5816.329079147797;749.2919191364109;749.7638243953137;765.693722511;773.0337078651686;776.8523980443698;25800.61299080515;26512.204633861016;26907;26946.595801063;27050.72949270482;919.1773362840221;924.1220840201835;929.3912487320815;939.5437072537676;1000.9249306302056;13976.650583735487;14175.64560885986;14340.56978290653;14513.492027778977;14563.92718036403;313445;316039;316128;316302;316980;40626.59373406287;40636.79681601573;40660.79669601692;40773.43757810893;40782.388264176065;1021.1218352217236;1063.723431907725;1064.3826580583243;1068.9251752377415;1163.3950345820126;24945.75054249434;25135.622965655897;25877.741222587534;26106.477870442865;26229.606555902057;39887;40338.394924075576;40393.99015024531;40415;40434.16960812715;254518;255005;255247;255599;255727;28544.714552854206;29307.560386594643;34360;34395.656043439245;35334.98957765856;39500.80249598734;39821.60178398179;39919.2016159681;39925.2014959705;40626.39060414037;32342.514862277556;32633.43131961575;32851.733758469316;34643;35072.47391289184;31760;31919.202019949687;32272.35455290928;32680.83659581687;33389;33035.504467433486;33046.50430243596;33605.83197083999;33698.49452258169;33771.83114084414;29201.269968250963;29441.55837662394;30463.390732185675;30732.692673072983;30757.23106922345;22618.886905565367;22970.655440168746;23284.417889552897;23343.299701008997;23432.648510272702 17:12:55 INFO - PROCESS | 29179 | |2;jquery.html;393.3882812226986;397.2214459659068;397.4554859842017;402.2337447163154;419.7637956218936;16447.58881027975;17391.565210869736;17786.46640600784;18139.830148407724;18140.27438902444;16268.33459893689;16318.83681163188;16503.752443713354;16914.577135571617;17595.56011099723;5025.195968645026;5060.558820002397;5214.06146893559;5215.322008138936;5217.426083130853;5087.236914462823;5132.820351287705;5168.508991645806;5169.663971841818;5175.585953123741;5142.202958541432;5146.794128234867;5152.304438900738;5166.250893620423;5171.4311425743135;4941.802327906879;5056.140456122468;5080.872978175576;5087.236914462823;5096.337476128098;4541.227991241464;4564.1556312082375;4566.429196350457;4568.0407114506;4574.794134263932;20237.797622023885;20279.5944081119;20347;20374.185032598678;20510.17959281627;2118.364490652798;2143.753468351144;2159.406163305088;2200.9559808803733;2222.29997550771;12736.554220602327;12877.484900603964;13013;13334.466621335136;13471.86528134716;1674.4558018643988;1682.167327173045;1683.158420789605;1712.6831536165848;1715.6311393050555;62928.37071629271;71386.64306678485;71435.28564714338;72290.63854680746;73405;9946.80106397868;10052.949735251277;10074.59701611942;10218.744531386776;10234.334768240189;14047.578572642837;14056.859431405555;14083.22542260165;14106.647333816736;14121.152730836182;6830.487713421513;6999.755008574777;7034.753783617549;7189.209186989427;7213.4589905757275;6422.165118534654;6455.9677201613695;6646.933530664632;6651.833704157435;6659.633720145342;6189.907151392733;6345.587536810092;6404.743810247538;6507.381798729113;6644.501662375341;1763.8236176382259;1783.7146056631136;1836.9448916532526;1840.9539761505803;1845.1327875898307;785.4305628419351;805.1545876829306;811.8295158016764;812.6830536090929;817.5421763812166;16441.506754797138;16524.339026439047;16882.74675879863;17036.659266814593;17444.651106977788;821.7206149909059;822.3791037766448;823.4976664234812;824.2952275804101;826.7850358906727;8832.498475259094;9456.33805633599;9540.809183816285;9659.9034009659;9873.111419972094;470447.8978052811;472336;474595;486194;488795;48859.755701221264;56464;72257;77209.61395192987;77547.22452775513;957.3107362698846;969.7721035556639;970.4710932541782;972.6547075788135;979.2704435195825;13734.381952812348;14335.56993290203;14347.35436905321;14455.78316325277;15042.398304067714;33825.49261761026;34343;34940.82529587336;34946.47580286247;35385.29229415449;44238.557614423444;44439.77780111079;44822.87942801456;45835;46115.30827037664;7528.585927773806;7547.245275472409;7676.193999630182;7962.283394494523;7974.202579741979;11692;11839;11844.940775296069;11989.580364687194;12117.878821212027;9167.17495425415;9464.95267523658;9714.562844672148;9906;10106.898931010595;10028.398296102258;10089.427196064957;10094;10353.378797272188;10408.323458975143;9892.15916647093;9952.800943981225;10359.119474844723;10808.513616887101;10938.781224375309;7392.149902761036;7412.184659687322;7528.360089392463;7558.58427786477;7698.114716807415;9812.165965892978;9865.950670246602;9905.108540231413;9977.60089596408;10196.38821670702 17:12:55 INFO - PROCESS | 29179 | |3;mootools.html;1198.4606926882905;1253.1854294708444;1295.3070107492492;1296.643423058659;1297.8377702787154;6677.065210870482;7445.739399121032;7662.157162712104;7671.846563068735;7878.763637090882;7730.304272615477;7777.416693747963;7784.96107519463;7793.142754297023;7802.726904558342;1761.4363403710818;2094.7695753467106;2113.2286715348864;2127.1385089038963;2129.861558998668;1823.7446757453968;1850.3708739028727;1850.9167087481096;1851.1022154255174;2041.795820417961;2056.321413933398;2102.232685069946;2107.1676687708346;2107.2940564910773;2109.1457959526415;2067.4934641013047;2085.092984551717;2089.8014688604558;2089.9268525601533;2095.0467537270506;2066.700328452366;2117.98941005294;2120.491082140275;2120.9893950530304;2124.3945475539554;7554.131274903401;7588.354989825872;7669.769906902802;7822.413319001098;7833.451658383916;4508.797104130322;4642.721436713808;4657.86026419208;4660.16117098922;4663.114008338405;2930.369970456362;2955.083923983572;2967.5251959686348;3017.3814368054714;3022.788404811664;1100.5817789240118;1102.322071925768;1124.7075760302298;1127.577158565538;1154.6536039188295;10603.151747860153;10629.043386095289;10629.681109566725;10630.946845265802;10669.19981001428;4007.859724909614;4229.873103806891;4262.403263543107;4289.764062976504;4306.956930430656;9099.727008189764;9103.954480227689;9108.271338292921;9114.36199466031;9124.49815260167;4687.437507499054;4711.081339138835;4717.198076327033;4719.2921061841;4723.0789995951145;4045.0089728016824;4164.770937598461;4265.1469706058915;4266.5946735060115;4314.848980285675;3270.296886169485;3357;3549.1836877518317;3592.946105808415;3667.5965643779164;1189.9107566932514;1201.375284851872;1223.5350566784564;1225.503671013241;1226.4113225651738;679.3003206697011;686.0722364463408;691.246541270018;698.2947223304499;719.086759815032;4631.421072365954;4643.883902902455;4664.346991421204;4669.322948172496;4733.479317275097;782.2842099479027;859.5186695450568;860.5009094725044;866.2766589897507;873.3188113271658;3129.4210571044428;3184.7611429142908;3215.083701145162;3235.6440791512914;3342.6824451677053;9043.231325337289;9389.201917836925;9533.285003624756;9533.523323833919;9556.426614403159;8700.912990870136;9476.478793666276;9487.383320084173;9498.287628427895;9506.85739713891;6983.441324694114;7295.598742069237;7311.451641126937;7324.707011719472;7358.7424440145405;4850.733209673502;4921.021860197724;5046.34397528319;5063.392392912874;5069;8187.959060204661;8338.79153021155;8494.150584941704;8596;8603.354748393771;8534.274586660204;8724.956375218084;8761.91238087611;8763.211310982042;8824.5587720615;6693.866122677617;6769.796906092825;6818.386345228953;6833.487488438486;6834.863302733818;6395.520335974914;6642.269350371359;7324.597147156957;7368.557886526825;7414.962925185339;6377.8724425512155;6496.350364963655;6553.737850486118;6657.7669781557415;6686.197656281277;6607.108040414482;6649.966750166218;6668;6688.197416310074;6710.194776626836;5406.270153529381;5433.782648694166;5442.836714898559;5443.183522471661;5446.237526746338;4279.529251782364;4321.848735294376;4348.847790327323;4388.587494555122;4483.439570053743;4346.587074227943;4351.673624478113;4369.978150109229;4453.332000199866;4586.082783443391 17:12:55 INFO - PROCESS | 29179 | |4;prototype.html;351.73971261266655;362.57216484548235;369.3407268026573;370.81088644791146;373.3708700839088;4732.5977291930185;4797.232442809147;4808.230683090711;4818.108649899771;4854.6844455110395;4599.793009314581;4715.481297057321;4756.619470442365;4785.712857228568;4788.281757736333;3774.698024158061;3783.1298801275725;3783.2433513297315;3786.4630697432785;3788.337041017811;3783.5270591176104;3785.3943369060817;3790.4693342931873;3791.748722921443;3795.601461846508;3602.2255215128744;3736.4680481002792;3756.1736417988136;3758.5677647070524;3765.4728338032696;3698.889033328991;3704.8329776120704;3711.870084547055;3714.6040803681476;3745.250949810049;3286.14560214346;3291.358185153896;3292.3579901919134;3294.7199488043543;3296.670332966708;4649.349091127245;4661.417322834645;4663.51033141522;4673.415823022122;4677.508861569554;4051.8784436466954;4228.302330115527;4251.404803327506;4258.765767882765;4275.807588658487;4972.577330926875;4977;4981.551660350586;4990.176620857555;4996.300517927493;3565.5186549815703;3576.2489877125836;3582.176099497104;3611.94582081269;3613;4792.904141917142;4934.309196712463;5117.5905927526455;5206.193040078794;5226.634135610509;3654.4734331370355;3662.4506324051604;3664.9816750916607;3667.688246499024;3676.117731744362;3643.1256498440184;3679.816009199529;3684.3184010958057;3705.7591256568235;3709.276691045274;3933.822977965941;4008.158286759755;4029.597040295947;4206.558311377261;4207.137536804948;4628.328892310664;4906.950930490721;4977.253411988231;5015.673981191211;5017.9247311290355;1330.5210124355224;1334.2928248028365;1338.4941940317144;1351.1285221032379;1351.4796803230827;875;933.5565606337071;934.7709811096325;936.5036530638772;942.8444306689456;4234.13697972891;4254.553271906405;4263.808128634157;4265.4881414230495;4271.765052922119;4982.626303027288;4987.650864439454;4991.876827713735;4993.325901003391;4993.725345106054;4050.6556942659613;4235.9621892636505;4247.235497610398;4266.914661706748;4276.685568684233;2610.5301045811934;2635.9736402636113;2639.2214296782495;2652.3634327761197;2659.880305386262;4716.716996980123;4728.59806916416;4733.171694953369;4742.976285118552;4747.667663263607;4794.2808578713475;5087.008033433519;5096.5791249156355;5109.56568691658;5122.846314610568;4703.929441058387;4722.598579120676;4730.266808644666;4730.763461826895;4763.3807605011125;4960.6031517477795;4964.733992831949;4983.508245877061;4991.176455884738;4991.57571606403;4744.478107408113;4919.803207871645;4935.71774769774;4938.061768264019;4957.529034741694;4646.930296045494;4798.376211092535;4806.890108956792;4830.903381932412;4887.84733846674;3581.964180358163;3679.1537946273043;3689.2252626948643;3690.206605579706;3695.6489133533346;5137.229415587692;5139.911487627148;5146.356705411824;5151.948480515147;5154.252633368065;4863.659543831898;4961.206207007006;4974.17926042199;5007.223880298559;5019.171836646912;4988.1270777613045;5030.446650868328;5034.496550344937;5043.899122017612;5046.495350465071;4965.106280869478;4970.25446183061;4974.37820272466;4990.426100998432;4991.9500804991485;4782.832600858953;4970.776315065758;5003.249512573143;5025.045241404122;5082.136036873666;4869.513048695102;4890.315355850255;4907.803687852446;4910.140725372974;4927.778249978831 17:12:55 INFO - PROCESS | 29179 | |5;yui.html;633.4486712967333;658.7101675262884;714.164427619685;724.4530379563429;745.269635756958;12097.516099356026;12556.686082847933;12574.056945729073;12585.056120790954;12587;12587.874121258785;12745;12758.106932514727;12773;12788.68028299293;783.6316931042401;786.1116937860224;804.8350088231898;810.3517186251005;812.8374325134968;813.8290958898639;824.8061705499233;824.8968878890139;825.8637324841424;826.3678286111129;854.2482615298528;854.6666799947994;856.3363393370157;856.4133568505594;856.5802756649229;849.8980122385353;854.1757204297883;854.974350769481;855.0893298636994;855.5465603230298;627.8618703885138;629.5624540944;629.8551333193345;631.1384959530254;631.356016862802;12364.258144511357;12498.937505312506;12622.873771262262;12659.050571207197;13828;11717;11920.344381059038;11998.064345544222;12119.939400302943;12193.817092743617;9768.218542516654;9834.557444914995;9914.454704991223;9991.550380232835;10036.447995360253;3197.936041279185;3202.5676533668125;3208.6149662040702;3224.790388624732;3237.7733558650903;57168.42831571672;57633.13550296749;57838.42161578372;58428.68497218166;59056.40943590552;10146.188304935735;10186.185105191567;10367;10383.740406489898;10401.84397234042;18720.15759290808;18725.531861703566;18905.90547047256;18917.621647567248;18973.146208420654;6262.373762623793;6265.404786545362;6318.557700960889;6398.104265402847;6444.033394990789;3592.946105808415;3604.693601043888;3608.332458495187;3645.6901163400876;3652.1782598915033;2897.652281726206;2935.0901220621677;2974.301039255773;2987.507061334899;2993.970060299369;492.1289317907284;493.92591111333587;501.5260578753116;501.70900877490965;503.98236061738396;593.7061154728351;608.6409018679001;616.608453631947;619.123939625425;622.5237693164732;8146.389020823462;8292.709755158661;8385.136205258113;8456.577171141418;8847.336449766293;539.3554702130995;539.4147350125158;557.1306288253232;558.8731049776494;561.6405500479697;3944.783036932939;4015.2170326785995;4080.755154690669;4115.567865374092;4208.431861698631;58213;60247;61152.694236528536;62848.68575657092;63259.6837015812;42933.35599966003;44283.33574996378;45040;45790.542094579294;48761;45157.54842451599;45945;46806.53193468022;46820.76589617098;47905.76047119742;8069.59652017385;8608.91391086081;8670.956645216733;8707;8730.869036964577;31492.370152596362;32696.34607307793;32731.181720456225;33717.83141084279;33963.4905476423;38399.42400864045;39495.210095797345;39881;40260.3960940592;40740;9937.155341796028;10059;10133.847992279974;10167.84748228792;10177.236707247133;12056.326874056267;12107.33409662477;12258.20321679088;12414;12867.163634363736;10774.191935604704;10966.890331096587;10967.23229373968;11205.943970280423;11477.942610286895;10522.263441559018;10649.680509584725;10929.672109836718;11047.72380690489;11062.225644205151;10592.788144237225;10653.978638514982;10899.455027248447;11222.102231821598;11283.266587671775;9000.909990900007;9022.458652480873;9038.728838134604;9204.355695101278;9465.66870159541;8328.250457458858;8680;8810.251128654136;9020.323475739215;9117.589708463267 17:12:55 INFO - PROCESS | 29179 | __end_tp_report 17:12:55 INFO - PROCESS | 29179 | __start_cc_report 17:12:55 INFO - PROCESS | 29179 | _x_x_mozilla_cycle_collect,1356 17:12:55 INFO - PROCESS | 29179 | __end_cc_report 17:12:55 INFO - PROCESS | 29179 | __startTimestamp1487207575066__endTimestamp 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | ------- Summary: start ------- 17:12:55 INFO - PROCESS | 29179 | Number of tests: 6 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | [#0] dojo.html Cycles:165 Average:4754.87 Median:4881.46 stddev:522.14 (10.7%) stddev-sans-first:495.96 17:12:55 INFO - PROCESS | 29179 | Values: 2612.9 2630.4 2657.3 2664.3 2670.9 4662.8 4743.9 4756.2 4827.2 4854.3 4721.6 4784.4 4794.6 4834.4 4846.4 4370.8 4399.1 4416.2 4432.9 4522.5 4115.0 4378.1 4414.4 4453.5 4461.8 4335.0 4351.4 4422.5 4458.1 4537.1 4400.3 4411.8 4423.3 4436.3 4456.3 4423.7 4469.7 4474.8 4483.1 4485.6 4778.6 4822.3 4838.1 4842.2 4873.9 4950.8 5176.1 5182.5 5185.0 5221.3 5012.7 5073.3 5090.5 5092.5 5093.1 4763.3 4773.4 4782.6 4886.4 4946.4 5470.5 5475.4 5479.7 5481.3 5485.7 4960.4 5001.5 5004.1 5101.2 5189.9 5151.0 5151.4 5151.5 5156.9 5162.2 5101.1 5123.3 5123.7 5124.5 5135.3 5105.8 5106.7 5112.4 5115.3 5117.8 4191.4 4216.3 4256.1 4309.9 4317.0 3686.0 3723.0 3740.5 3742.1 3763.5 4428.1 4433.5 4435.0 4437.8 4477.8 5179.7 5182.9 5192.2 5194.2 5235.4 4354.7 4398.3 4427.5 4457.0 4475.6 4568.7 4623.8 4633.3 4637.3 4645.9 4838.7 4879.3 4881.5 4893.5 4932.0 4997.3 5158.6 5176.5 5190.3 5244.8 4789.3 4858.7 4893.0 4938.5 4943.1 5145.5 5185.4 5191.5 5192.9 5200.3 5221.5 5231.7 5235.7 5239.5 5274.4 4828.8 5081.5 5084.8 5084.9 5091.9 4862.2 4910.4 4988.4 4990.3 4990.3 4878.2 4898.9 4900.7 4939.1 4946.4 4986.4 4987.7 5017.6 5018.2 5046.9 4766.2 4866.4 4890.5 4891.8 4938.7 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | [#1] ext.html Cycles:180 Average:41937.00 Median:19298.51 stddev:69514.14 (360.2%) stddev-sans-first:69639.63 17:12:55 INFO - PROCESS | 29179 | Values: 531.2 552.1 562.6 571.8 625.1 25958.7 36525.8 37891.8 38055.4 40746.4 37747.6 38017.0 38081.4 38098.0 38145.4 6651.4 7559.8 7791.5 7804.2 7804.8 6654.6 9216.8 9482.0 9485.6 9782.8 9711.0 9755.7 9783.3 9784.9 9789.2 9638.5 9705.0 9721.2 9729.6 9730.1 9308.4 9435.1 9455.6 9456.5 9464.0 36804.4 36986.3 37018.3 37079.3 37173.8 3445.9 3551.3 3562.9 3586.1 3615.8 27398.0 27990.0 28027.4 28391.4 28443.0 3302.8 3386.4 3478.4 3482.8 3524.5 217053.0 217484.0 217708.0 217743.0 218911.0 42948.6 44507.0 51853.5 52687.5 52822.5 55083.9 56385.0 56408.7 61763.0 61859.7 13629.1 13723.3 13726.5 13734.8 13746.5 10677.3 10911.2 10911.2 10978.9 10988.4 16101.4 16126.8 16172.9 16213.2 16286.7 5705.1 5728.7 5761.5 5805.2 5816.3 749.3 749.8 765.7 773.0 776.9 25800.6 26512.2 26907.0 26946.6 27050.7 919.2 924.1 929.4 939.5 1000.9 13976.7 14175.6 14340.6 14513.5 14563.9 313445.0 316039.0 316128.0 316302.0 316980.0 40626.6 40636.8 40660.8 40773.4 40782.4 1021.1 1063.7 1064.4 1068.9 1163.4 24945.8 25135.6 25877.7 26106.5 26229.6 39887.0 40338.4 40394.0 40415.0 40434.2 254518.0 255005.0 255247.0 255599.0 255727.0 28544.7 29307.6 34360.0 34395.7 35335.0 39500.8 39821.6 39919.2 39925.2 40626.4 32342.5 32633.4 32851.7 34643.0 35072.5 31760.0 31919.2 32272.4 32680.8 33389.0 33035.5 33046.5 33605.8 33698.5 33771.8 29201.3 29441.6 30463.4 30732.7 30757.2 22618.9 22970.7 23284.4 23343.3 23432.6 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | [#2] jquery.html Cycles:180 Average:26355.56 Median:473465.50 stddev:78342.92 (16.5%) stddev-sans-first:78538.44 17:12:55 INFO - PROCESS | 29179 | Values: 393.4 397.2 397.5 402.2 419.8 16447.6 17391.6 17786.5 18139.8 18140.3 16268.3 16318.8 16503.8 16914.6 17595.6 5025.2 5060.6 5214.1 5215.3 5217.4 5087.2 5132.8 5168.5 5169.7 5175.6 5142.2 5146.8 5152.3 5166.3 5171.4 4941.8 5056.1 5080.9 5087.2 5096.3 4541.2 4564.2 4566.4 4568.0 4574.8 20237.8 20279.6 20347.0 20374.2 20510.2 2118.4 2143.8 2159.4 2201.0 2222.3 12736.6 12877.5 13013.0 13334.5 13471.9 1674.5 1682.2 1683.2 1712.7 1715.6 62928.4 71386.6 71435.3 72290.6 73405.0 9946.8 10052.9 10074.6 10218.7 10234.3 14047.6 14056.9 14083.2 14106.6 14121.2 6830.5 6999.8 7034.8 7189.2 7213.5 6422.2 6456.0 6646.9 6651.8 6659.6 6189.9 6345.6 6404.7 6507.4 6644.5 1763.8 1783.7 1836.9 1841.0 1845.1 785.4 805.2 811.8 812.7 817.5 16441.5 16524.3 16882.7 17036.7 17444.7 821.7 822.4 823.5 824.3 826.8 8832.5 9456.3 9540.8 9659.9 9873.1 470447.9 472336.0 474595.0 486194.0 488795.0 48859.8 56464.0 72257.0 77209.6 77547.2 957.3 969.8 970.5 972.7 979.3 13734.4 14335.6 14347.4 14455.8 15042.4 33825.5 34343.0 34940.8 34946.5 35385.3 44238.6 44439.8 44822.9 45835.0 46115.3 7528.6 7547.2 7676.2 7962.3 7974.2 11692.0 11839.0 11844.9 11989.6 12117.9 9167.2 9465.0 9714.6 9906.0 10106.9 10028.4 10089.4 10094.0 10353.4 10408.3 9892.2 9952.8 10359.1 10808.5 10938.8 7392.1 7412.2 7528.4 7558.6 7698.1 9812.2 9866.0 9905.1 9977.6 10196.4 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | [#3] mootools.html Cycles:180 Average:4988.56 Median:4714.14 stddev:2830.03 (60.0%) stddev-sans-first:2823.64 17:12:55 INFO - PROCESS | 29179 | Values: 1198.5 1253.2 1295.3 1296.6 1297.8 6677.1 7445.7 7662.2 7671.8 7878.8 7730.3 7777.4 7785.0 7793.1 7802.7 1761.4 2094.8 2113.2 2127.1 2129.9 1823.7 1850.4 1850.9 1851.1 2041.8 2056.3 2102.2 2107.2 2107.3 2109.1 2067.5 2085.1 2089.8 2089.9 2095.0 2066.7 2118.0 2120.5 2121.0 2124.4 7554.1 7588.4 7669.8 7822.4 7833.5 4508.8 4642.7 4657.9 4660.2 4663.1 2930.4 2955.1 2967.5 3017.4 3022.8 1100.6 1102.3 1124.7 1127.6 1154.7 10603.2 10629.0 10629.7 10630.9 10669.2 4007.9 4229.9 4262.4 4289.8 4307.0 9099.7 9104.0 9108.3 9114.4 9124.5 4687.4 4711.1 4717.2 4719.3 4723.1 4045.0 4164.8 4265.1 4266.6 4314.8 3270.3 3357.0 3549.2 3592.9 3667.6 1189.9 1201.4 1223.5 1225.5 1226.4 679.3 686.1 691.2 698.3 719.1 4631.4 4643.9 4664.3 4669.3 4733.5 782.3 859.5 860.5 866.3 873.3 3129.4 3184.8 3215.1 3235.6 3342.7 9043.2 9389.2 9533.3 9533.5 9556.4 8700.9 9476.5 9487.4 9498.3 9506.9 6983.4 7295.6 7311.5 7324.7 7358.7 4850.7 4921.0 5046.3 5063.4 5069.0 8188.0 8338.8 8494.2 8596.0 8603.4 8534.3 8725.0 8761.9 8763.2 8824.6 6693.9 6769.8 6818.4 6833.5 6834.9 6395.5 6642.3 7324.6 7368.6 7415.0 6377.9 6496.4 6553.7 6657.8 6686.2 6607.1 6650.0 6668.0 6688.2 6710.2 5406.3 5433.8 5442.8 5443.2 5446.2 4279.5 4321.8 4348.8 4388.6 4483.4 4346.6 4351.7 4370.0 4453.3 4586.1 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | [#4] prototype.html Cycles:180 Average:4118.03 Median:4719.66 stddev:1166.35 (24.7%) stddev-sans-first:1134.85 17:12:55 INFO - PROCESS | 29179 | Values: 351.7 362.6 369.3 370.8 373.4 4732.6 4797.2 4808.2 4818.1 4854.7 4599.8 4715.5 4756.6 4785.7 4788.3 3774.7 3783.1 3783.2 3786.5 3788.3 3783.5 3785.4 3790.5 3791.7 3795.6 3602.2 3736.5 3756.2 3758.6 3765.5 3698.9 3704.8 3711.9 3714.6 3745.3 3286.1 3291.4 3292.4 3294.7 3296.7 4649.3 4661.4 4663.5 4673.4 4677.5 4051.9 4228.3 4251.4 4258.8 4275.8 4972.6 4977.0 4981.6 4990.2 4996.3 3565.5 3576.2 3582.2 3611.9 3613.0 4792.9 4934.3 5117.6 5206.2 5226.6 3654.5 3662.5 3665.0 3667.7 3676.1 3643.1 3679.8 3684.3 3705.8 3709.3 3933.8 4008.2 4029.6 4206.6 4207.1 4628.3 4907.0 4977.3 5015.7 5017.9 1330.5 1334.3 1338.5 1351.1 1351.5 875.0 933.6 934.8 936.5 942.8 4234.1 4254.6 4263.8 4265.5 4271.8 4982.6 4987.7 4991.9 4993.3 4993.7 4050.7 4236.0 4247.2 4266.9 4276.7 2610.5 2636.0 2639.2 2652.4 2659.9 4716.7 4728.6 4733.2 4743.0 4747.7 4794.3 5087.0 5096.6 5109.6 5122.8 4703.9 4722.6 4730.3 4730.8 4763.4 4960.6 4964.7 4983.5 4991.2 4991.6 4744.5 4919.8 4935.7 4938.1 4957.5 4646.9 4798.4 4806.9 4830.9 4887.8 3582.0 3679.2 3689.2 3690.2 3695.6 5137.2 5139.9 5146.4 5151.9 5154.3 4863.7 4961.2 4974.2 5007.2 5019.2 4988.1 5030.4 5034.5 5043.9 5046.5 4965.1 4970.3 4974.4 4990.4 4992.0 4782.8 4970.8 5003.2 5025.0 5082.1 4869.5 4890.3 4907.8 4910.1 4927.8 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - PROCESS | 29179 | [#5] yui.html Cycles:180 Average:13597.60 Median:46375.77 stddev:16327.75 (35.2%) stddev-sans-first:16344.53 17:12:55 INFO - PROCESS | 29179 | Values: 633.4 658.7 714.2 724.5 745.3 12097.5 12556.7 12574.1 12585.1 12587.0 12587.9 12745.0 12758.1 12773.0 12788.7 783.6 786.1 804.8 810.4 812.8 813.8 824.8 824.9 825.9 826.4 854.2 854.7 856.3 856.4 856.6 849.9 854.2 855.0 855.1 855.5 627.9 629.6 629.9 631.1 631.4 12364.3 12498.9 12622.9 12659.1 13828.0 11717.0 11920.3 11998.1 12119.9 12193.8 9768.2 9834.6 9914.5 9991.6 10036.4 3197.9 3202.6 3208.6 3224.8 3237.8 57168.4 57633.1 57838.4 58428.7 59056.4 10146.2 10186.2 10367.0 10383.7 10401.8 18720.2 18725.5 18905.9 18917.6 18973.1 6262.4 6265.4 6318.6 6398.1 6444.0 3592.9 3604.7 3608.3 3645.7 3652.2 2897.7 2935.1 2974.3 2987.5 2994.0 492.1 493.9 501.5 501.7 504.0 593.7 608.6 616.6 619.1 622.5 8146.4 8292.7 8385.1 8456.6 8847.3 539.4 539.4 557.1 558.9 561.6 3944.8 4015.2 4080.8 4115.6 4208.4 58213.0 60247.0 61152.7 62848.7 63259.7 42933.4 44283.3 45040.0 45790.5 48761.0 45157.5 45945.0 46806.5 46820.8 47905.8 8069.6 8608.9 8671.0 8707.0 8730.9 31492.4 32696.3 32731.2 33717.8 33963.5 38399.4 39495.2 39881.0 40260.4 40740.0 9937.2 10059.0 10133.8 10167.8 10177.2 12056.3 12107.3 12258.2 12414.0 12867.2 10774.2 10966.9 10967.2 11205.9 11477.9 10522.3 10649.7 10929.7 11047.7 11062.2 10592.8 10654.0 10899.5 11222.1 11283.3 9000.9 9022.5 9038.7 9204.4 9465.7 8328.3 8680.0 8810.3 9020.3 9117.6 17:12:55 INFO - PROCESS | 29179 | -------- Summary: end -------- 17:12:55 INFO - PROCESS | 29179 | 17:12:55 INFO - TEST-INFO | 29179: exit 0 17:12:55 INFO - TEST-OK | dromaeo_css | took 1122980ms 17:12:55 INFO - TEST-START | kraken 17:12:55 INFO - operating with platform_type : linux_ 17:12:55 INFO - Initialising browser for kraken test... 17:12:55 INFO - TEST-INFO | started process 29482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEv3fGF/profile http://localhost:51262/getInfo.html) 17:12:58 INFO - PROCESS | 29482 | Iterating over profile /tmp/tmpEv3fGF/profile 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/key3.db = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | __metrics Screen width/height:1600/1200 17:13:00 INFO - PROCESS | 29482 | colorDepth:24 17:13:00 INFO - PROCESS | 29482 | Browser inner width/height: 1024/697 17:13:00 INFO - PROCESS | 29482 | __metrics 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/crashes = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/gmp = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/extensions.json = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/extensions = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/addons.json = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/extensions.ini = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/safebrowsing = Wed Feb 15 2017 17:13:00 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/cache2 = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/permissions.sqlite = Wed Feb 15 2017 17:12:57 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/pluginreg.dat = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/places.sqlite-wal = Wed Feb 15 2017 17:13:00 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/prefs.js = Wed Feb 15 2017 16:53:32 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | Stat failure 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/.parentlock = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/bookmarkbackups = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/content-prefs.sqlite = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/minidumps = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/thumbnails = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/places.sqlite-shm = Wed Feb 15 2017 17:12:57 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/startupCache = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/secmod.db = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/localstore.rdf = Wed Feb 15 2017 16:53:32 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/places.sqlite = Wed Feb 15 2017 17:13:00 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/directoryLinks.json = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/user.js = Wed Feb 15 2017 17:12:55 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/frequencyCap.json = Wed Feb 15 2017 17:12:58 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/cert8.db = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/blocklist.xml = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/sessionCheckpoints.json = Wed Feb 15 2017 17:13:00 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | No birth date. Using mtime. 17:13:00 INFO - PROCESS | 29482 | Using date: /tmp/tmpEv3fGF/profile/compatibility.ini = Wed Feb 15 2017 17:12:56 GMT-0800 (PST) 17:13:00 INFO - PROCESS | 29482 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:13:00 INFO - PROCESS | 29482 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:13:01 INFO - TEST-INFO | 29482: exit 0 17:13:01 INFO - Browser initialized. 17:13:01 INFO - Running cycle 1/1 for kraken test... 17:13:01 INFO - TEST-INFO | started process 29564 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEv3fGF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:13:14 INFO - PROCESS | 29564 | RSS: Main: 154066944 17:13:14 INFO - PROCESS | 29564 | 17:13:16 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51262/tests/kraken/driver.html?testName=audio-beat-detection) 17:13:17 INFO - PROCESS | 29564 | RSS: Main: 209473536 17:13:17 INFO - PROCESS | 29564 | 17:13:20 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51262/tests/kraken/driver.html?testName=audio-dft) 17:13:21 INFO - PROCESS | 29564 | RSS: Main: 188448768 17:13:21 INFO - PROCESS | 29564 | 17:13:24 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51262/tests/kraken/driver.html?testName=audio-fft) 17:13:24 INFO - PROCESS | 29564 | RSS: Main: 271769600 17:13:24 INFO - PROCESS | 29564 | 17:13:26 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51262/tests/kraken/driver.html?testName=audio-oscillator) 17:13:27 INFO - PROCESS | 29564 | RSS: Main: 182075392 17:13:27 INFO - PROCESS | 29564 | 17:13:29 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:13:30 INFO - PROCESS | 29564 | RSS: Main: 185733120 17:13:30 INFO - PROCESS | 29564 | 17:13:33 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51262/tests/kraken/driver.html?testName=imaging-darkroom) 17:13:34 INFO - PROCESS | 29564 | RSS: Main: 224006144 17:13:34 INFO - PROCESS | 29564 | 17:13:37 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51262/tests/kraken/driver.html?testName=imaging-desaturate) 17:13:38 INFO - PROCESS | 29564 | RSS: Main: 224563200 17:13:38 INFO - PROCESS | 29564 | 17:13:41 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51262/tests/kraken/driver.html?testName=json-parse-financial) 17:13:41 INFO - PROCESS | 29564 | RSS: Main: 223166464 17:13:41 INFO - PROCESS | 29564 | 17:13:43 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:13:43 INFO - PROCESS | 29564 | RSS: Main: 185638912 17:13:43 INFO - PROCESS | 29564 | 17:13:45 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:13:46 INFO - PROCESS | 29564 | RSS: Main: 192614400 17:13:46 INFO - PROCESS | 29564 | 17:13:48 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:13:49 INFO - PROCESS | 29564 | RSS: Main: 201289728 17:13:49 INFO - PROCESS | 29564 | 17:13:52 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:13:52 INFO - PROCESS | 29564 | RSS: Main: 210841600 17:13:52 INFO - PROCESS | 29564 | 17:13:56 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:13:56 INFO - PROCESS | 29564 | RSS: Main: 206565376 17:13:56 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | Cycle 1(1): loaded http://localhost:51262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51262/tests/kraken/driver.html?testName=ai-astar) 17:13:58 INFO - PROCESS | 29564 | RSS: Main: 258031616 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | __start_tp_report 17:13:58 INFO - PROCESS | 29564 | _x_x_mozilla_page_load 17:13:58 INFO - PROCESS | 29564 | _x_x_mozilla_page_load_details 17:13:58 INFO - PROCESS | 29564 | |i|pagename|runs| 17:13:58 INFO - PROCESS | 29564 | |0;ai-astar;114;114;114;116;115;114;114;115;113;115 17:13:58 INFO - PROCESS | 29564 | |1;audio-beat-detection;156;131;124;166;128;127;142;134;150;126 17:13:58 INFO - PROCESS | 29564 | |2;audio-dft;162;161;162;162;162;162;162;160;160;161 17:13:58 INFO - PROCESS | 29564 | |3;audio-fft;76;76;77;114;77;78;76;77;103;76 17:13:58 INFO - PROCESS | 29564 | |4;audio-oscillator;85;84;106;84;84;84;109;85;85;108 17:13:58 INFO - PROCESS | 29564 | |5;imaging-gaussian-blur;114;114;113;113;114;113;113;113;114;113 17:13:58 INFO - PROCESS | 29564 | |6;imaging-darkroom;149;149;150;149;149;150;149;148;150;149 17:13:58 INFO - PROCESS | 29564 | |7;imaging-desaturate;96;96;95;95;95;96;95;95;95;95 17:13:58 INFO - PROCESS | 29564 | |8;json-parse-financial;62;62;61;63;62;64;63;62;63;65 17:13:58 INFO - PROCESS | 29564 | |9;json-stringify-tinderbox;64;65;64;65;65;65;66;66;66;71 17:13:58 INFO - PROCESS | 29564 | |10;stanford-crypto-aes;87;87;87;86;87;89;88;87;87;86 17:13:58 INFO - PROCESS | 29564 | |11;stanford-crypto-ccm;161;145;136;137;139;134;136;135;179;152 17:13:58 INFO - PROCESS | 29564 | |12;stanford-crypto-pbkdf2;187;188;195;187;190;184;185;186;185;195 17:13:58 INFO - PROCESS | 29564 | |13;stanford-crypto-sha256-iterative;75;70;71;71;76;71;71;71;70;70 17:13:58 INFO - PROCESS | 29564 | __end_tp_report 17:13:58 INFO - PROCESS | 29564 | __start_cc_report 17:13:58 INFO - PROCESS | 29564 | _x_x_mozilla_cycle_collect,1945 17:13:58 INFO - PROCESS | 29564 | __end_cc_report 17:13:58 INFO - PROCESS | 29564 | __startTimestamp1487207638803__endTimestamp 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | ------- Summary: start ------- 17:13:58 INFO - PROCESS | 29564 | Number of tests: 14 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 17:13:58 INFO - PROCESS | 29564 | Values: 114.0 114.0 114.0 116.0 115.0 114.0 114.0 115.0 113.0 115.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:138.00 stddev:14.50 (10.5%) stddev-sans-first:13.91 17:13:58 INFO - PROCESS | 29564 | Values: 156.0 131.0 124.0 166.0 128.0 127.0 142.0 134.0 150.0 126.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 17:13:58 INFO - PROCESS | 29564 | Values: 162.0 161.0 162.0 162.0 162.0 162.0 162.0 160.0 160.0 161.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#3] audio-fft Cycles:10 Average:83.00 Median:76.50 stddev:13.70 (17.9%) stddev-sans-first:14.30 17:13:58 INFO - PROCESS | 29564 | Values: 76.0 76.0 77.0 114.0 77.0 78.0 76.0 77.0 103.0 76.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:84.00 stddev:11.26 (13.4%) stddev-sans-first:11.70 17:13:58 INFO - PROCESS | 29564 | Values: 85.0 84.0 106.0 84.0 84.0 84.0 109.0 85.0 85.0 108.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 17:13:58 INFO - PROCESS | 29564 | Values: 114.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 114.0 113.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 17:13:58 INFO - PROCESS | 29564 | Values: 149.0 149.0 150.0 149.0 149.0 150.0 149.0 148.0 150.0 149.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 17:13:58 INFO - PROCESS | 29564 | Values: 96.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:1.16 (1.8%) stddev-sans-first:1.20 17:13:58 INFO - PROCESS | 29564 | Values: 62.0 62.0 61.0 63.0 62.0 64.0 63.0 62.0 63.0 65.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.50 stddev:2.00 (3.1%) stddev-sans-first:2.03 17:13:58 INFO - PROCESS | 29564 | Values: 64.0 65.0 64.0 65.0 65.0 65.0 66.0 66.0 66.0 71.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 17:13:58 INFO - PROCESS | 29564 | Values: 87.0 87.0 87.0 86.0 87.0 89.0 88.0 87.0 87.0 86.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:142.00 stddev:14.69 (10.3%) stddev-sans-first:14.46 17:13:58 INFO - PROCESS | 29564 | Values: 161.0 145.0 136.0 137.0 139.0 134.0 136.0 135.0 179.0 152.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:187.50 stddev:3.97 (2.1%) stddev-sans-first:4.18 17:13:58 INFO - PROCESS | 29564 | Values: 187.0 188.0 195.0 187.0 190.0 184.0 185.0 186.0 185.0 195.0 17:13:58 INFO - PROCESS | 29564 | 17:13:58 INFO - PROCESS | 29564 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:71.00 stddev:2.12 (3.0%) stddev-sans-first:1.86 17:13:58 INFO - PROCESS | 29564 | Values: 75.0 70.0 71.0 71.0 76.0 71.0 71.0 71.0 70.0 70.0 17:13:58 INFO - PROCESS | 29564 | -------- Summary: end -------- 17:13:58 INFO - PROCESS | 29564 | 17:13:59 INFO - TEST-INFO | 29564: exit 0 17:13:59 INFO - TEST-OK | kraken | took 64038ms 17:13:59 INFO - SUITE-END | took 1187s 17:14:00 INFO - Completed test suite (00:19:47) 17:14:00 INFO - javascript benchmark 17:14:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6262.06531288193, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2612.947741045179, 2630.4081581644145, 2657.348949507371, 2664.3205982474465, 2670.919872403829, 4662.766861656921, 4743.8814029649275, 4756.242568370987, 4827.155247831624, 4854.296127061579, 4721.598664113554, 4784.425868895737, 4794.592459640935, 4834.395700537433, 4846.39420072491, 4370.781460926941, 4399.120175964804, 4416.205083085043, 4432.867013989569, 4522.479914809806, 4114.958850411487, 4378.124375124988, 4414.448193975753, 4453.465584129909, 4461.843835465759, 4335.0, 4351.390805287262, 4422.469303683579, 4458.1306645204195, 4537.069900670371, 4400.273954797454, 4411.779411029435, 4423.336499525065, 4436.312371582378, 4456.286994080931, 4423.690341676084, 4469.687121901468, 4474.843380481668, 4483.058557702886, 4485.641148708096, 4778.566430070965, 4822.252550854623, 4838.104950584153, 4842.15262329091, 4873.853784386474, 4950.8267210647455, 5176.094183517869, 5182.455842136596, 5185.0148471790235, 5221.2951251580935, 5012.749362531858, 5073.340465739467, 5090.516400941905, 5092.541671249568, 5093.083245015896, 4763.261694437368, 4773.403324584427, 4782.617390608743, 4886.438059623118, 4946.35697359341, 5470.480304371078, 5475.425080366584, 5479.726013699299, 5481.287432633732, 5485.698286594195, 4960.404751429852, 5001.474845141208, 5004.149294619923, 5101.158308879068, 5189.870253243699, 5151.021305951857, 5151.407588127413, 5151.459096794782, 5156.92264616031, 5162.225666150108, 5101.056304583664, 5123.333966584395, 5123.718195499283, 5124.461931497139, 5135.332406787168, 5105.795768169306, 5106.744662766847, 5112.3865136183895, 5115.3335752166, 5117.8464646060675, 4191.371294305878, 4216.3464662977285, 4256.127493863751, 4309.870703878826, 4316.978415107905, 3685.981570092132, 3723.0, 3740.495033170542, 3742.0644838790304, 3763.5483741950593, 4428.070105277928, 4433.512313645497, 4434.957784920541, 4437.844675436344, 4477.753723545171, 5179.741012949337, 5182.948170518322, 5192.169252919514, 5194.194899790538, 5235.3717553893775, 4354.651627869763, 4398.296272596369, 4427.468703755506, 4457.04173602677, 4475.5748203920575, 4568.725876447424, 4623.768811559476, 4633.30500424939, 4637.3275874997935, 4645.907081858344, 4838.709677419366, 4879.316895634614, 4881.463039065771, 4893.486183950634, 4932.0, 4997.3253610762085, 5158.59184384806, 5176.534111929794, 5190.273361729436, 5244.816431424882, 4789.3055506952, 4858.732769697701, 4892.975535122301, 4938.530839570235, 4943.110240156662, 5145.511176438233, 5185.403678576861, 5191.454897235736, 5192.896142077062, 5200.349956255468, 5221.503957124067, 5231.686098834082, 5235.712035837913, 5239.502247286502, 5274.393444753902, 4828.758562071812, 5081.4918508148885, 5084.847454576368, 5084.949150508448, 5091.923621145612, 4862.222044472867, 4910.410750709938, 4988.4263309719845, 4990.276409920614, 4990.301357809837, 4878.243872199815, 4898.8775280618265, 4900.656954013185, 4939.110960027086, 4946.3817022872145, 4986.351774269322, 4987.6508644396, 5017.648764586444, 5018.172001619692, 5046.924296135488, 4766.237402015661, 4866.367372241728, 4890.510948905081, 4891.755412229303, 4938.654294199371], "value": 4720.125451870162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.208499335989, 552.061495457722, 562.5780664501624, 571.8113022702507, 625.0592857749087, 25958.740412595867, 36525.81737091317, 37891.81054094729, 38055.42916856249, 40746.38880416796, 37747.62252377476, 38017.04957376067, 38081.428778568275, 38098.0, 38145.42781858274, 6651.368120028614, 7559.811004724885, 7791.454598178129, 7804.180561041094, 7804.765857024298, 6654.633995130266, 9216.815663686722, 9481.952590237039, 9485.620575176985, 9782.804343913118, 9710.951445242765, 9755.707328780149, 9783.266255030865, 9784.853227201598, 9789.216862651007, 9638.518074096302, 9704.951475242578, 9721.222302215805, 9729.61081556744, 9730.12428881404, 9308.394954327947, 9435.056494350578, 9455.574499147555, 9456.479893605849, 9464.0, 36804.44793328102, 36986.26027479435, 37018.25963480742, 37079.25841483155, 37173.814130929444, 3445.9483107753404, 3551.325248202859, 3562.946555801639, 3586.103474131467, 3615.8192090395373, 27398.0, 27990.0, 28027.439451211067, 28391.432171356664, 28443.0, 3302.768806183568, 3386.407378708716, 3478.4260597001467, 3482.8258587070545, 3524.541809564766, 217052.99999999843, 217484.0, 217708.0, 217743.0, 218911.0, 42948.57051429446, 44507.0, 51853.481465184865, 52687.473125268254, 52822.47177528252, 55083.940766515654, 56385.0, 56408.71795640996, 61763.0, 61859.690701546206, 13629.114107582975, 13723.313834308245, 13726.519571814939, 13734.793978090336, 13746.450141994408, 10677.305975111592, 10911.181661375269, 10911.23621346514, 10978.890211097787, 10988.395638239814, 16101.436449724204, 16126.838731612534, 16172.919135404247, 16213.189340533163, 16286.674266514603, 5705.115707065412, 5728.684922329312, 5761.53907687384, 5805.158252053513, 5816.329079147797, 749.2919191364109, 749.7638243953137, 765.693722511, 773.0337078651686, 776.8523980443698, 25800.61299080515, 26512.204633861016, 26907.0, 26946.595801063, 27050.72949270482, 919.1773362840221, 924.1220840201835, 929.3912487320815, 939.5437072537676, 1000.9249306302056, 13976.650583735487, 14175.64560885986, 14340.56978290653, 14513.492027778977, 14563.92718036403, 313445.0, 316039.0, 316128.0, 316302.0, 316980.0, 40626.59373406287, 40636.79681601573, 40660.79669601692, 40773.43757810893, 40782.388264176065, 1021.1218352217236, 1063.723431907725, 1064.3826580583243, 1068.9251752377415, 1163.3950345820126, 24945.75054249434, 25135.622965655897, 25877.741222587534, 26106.477870442865, 26229.606555902057, 39887.0, 40338.394924075576, 40393.99015024531, 40415.0, 40434.16960812715, 254518.0, 255005.0, 255247.0, 255599.0, 255727.0, 28544.714552854206, 29307.560386594643, 34360.0, 34395.656043439245, 35334.98957765856, 39500.80249598734, 39821.60178398179, 39919.2016159681, 39925.2014959705, 40626.39060414037, 32342.514862277556, 32633.43131961575, 32851.733758469316, 34643.0, 35072.47391289184, 31760.0, 31919.202019949687, 32272.35455290928, 32680.83659581687, 33389.0, 33035.504467433486, 33046.50430243596, 33605.83197083999, 33698.49452258169, 33771.83114084414, 29201.269968250963, 29441.55837662394, 30463.390732185675, 30732.692673072983, 30757.23106922345, 22618.886905565367, 22970.655440168746, 23284.417889552897, 23343.299701008997, 23432.648510272702], "value": 16922.44157623735, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.3882812226986, 397.2214459659068, 397.4554859842017, 402.2337447163154, 419.7637956218936, 16447.58881027975, 17391.565210869736, 17786.46640600784, 18139.830148407724, 18140.27438902444, 16268.33459893689, 16318.83681163188, 16503.752443713354, 16914.577135571617, 17595.56011099723, 5025.195968645026, 5060.558820002397, 5214.06146893559, 5215.322008138936, 5217.426083130853, 5087.236914462823, 5132.820351287705, 5168.508991645806, 5169.663971841818, 5175.585953123741, 5142.202958541432, 5146.794128234867, 5152.304438900738, 5166.250893620423, 5171.4311425743135, 4941.802327906879, 5056.140456122468, 5080.872978175576, 5087.236914462823, 5096.337476128098, 4541.227991241464, 4564.1556312082375, 4566.429196350457, 4568.0407114506, 4574.794134263932, 20237.797622023885, 20279.5944081119, 20347.0, 20374.185032598678, 20510.17959281627, 2118.364490652798, 2143.753468351144, 2159.406163305088, 2200.9559808803733, 2222.29997550771, 12736.554220602327, 12877.484900603964, 13013.0, 13334.466621335136, 13471.86528134716, 1674.4558018643988, 1682.167327173045, 1683.158420789605, 1712.6831536165848, 1715.6311393050555, 62928.37071629271, 71386.64306678485, 71435.28564714338, 72290.63854680746, 73405.0, 9946.80106397868, 10052.949735251277, 10074.59701611942, 10218.744531386776, 10234.334768240189, 14047.578572642837, 14056.859431405555, 14083.22542260165, 14106.647333816736, 14121.152730836182, 6830.487713421513, 6999.755008574777, 7034.753783617549, 7189.209186989427, 7213.4589905757275, 6422.165118534654, 6455.9677201613695, 6646.933530664632, 6651.833704157435, 6659.633720145342, 6189.907151392733, 6345.587536810092, 6404.743810247538, 6507.381798729113, 6644.501662375341, 1763.8236176382259, 1783.7146056631136, 1836.9448916532526, 1840.9539761505803, 1845.1327875898307, 785.4305628419351, 805.1545876829306, 811.8295158016764, 812.6830536090929, 817.5421763812166, 16441.506754797138, 16524.339026439047, 16882.74675879863, 17036.659266814593, 17444.651106977788, 821.7206149909059, 822.3791037766448, 823.4976664234812, 824.2952275804101, 826.7850358906727, 8832.498475259094, 9456.33805633599, 9540.809183816285, 9659.9034009659, 9873.111419972094, 470447.8978052811, 472336.0, 474595.0, 486194.0, 488795.0, 48859.755701221264, 56464.0, 72257.0, 77209.61395192987, 77547.22452775513, 957.3107362698846, 969.7721035556639, 970.4710932541782, 972.6547075788135, 979.2704435195825, 13734.381952812348, 14335.56993290203, 14347.35436905321, 14455.78316325277, 15042.398304067714, 33825.49261761026, 34343.0, 34940.82529587336, 34946.47580286247, 35385.29229415449, 44238.557614423444, 44439.77780111079, 44822.87942801456, 45835.0, 46115.30827037664, 7528.585927773806, 7547.245275472409, 7676.193999630182, 7962.283394494523, 7974.202579741979, 11692.0, 11839.0, 11844.940775296069, 11989.580364687194, 12117.878821212027, 9167.17495425415, 9464.95267523658, 9714.562844672148, 9906.0, 10106.898931010595, 10028.398296102258, 10089.427196064957, 10094.0, 10353.378797272188, 10408.323458975143, 9892.15916647093, 9952.800943981225, 10359.119474844723, 10808.513616887101, 10938.781224375309, 7392.149902761036, 7412.184659687322, 7528.360089392463, 7558.58427786477, 7698.114716807415, 9812.165965892978, 9865.950670246602, 9905.108540231413, 9977.60089596408, 10196.38821670702], "value": 8260.114284888394, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1198.4606926882905, 1253.1854294708444, 1295.3070107492492, 1296.643423058659, 1297.8377702787154, 6677.065210870482, 7445.739399121032, 7662.157162712104, 7671.846563068735, 7878.763637090882, 7730.304272615477, 7777.416693747963, 7784.96107519463, 7793.142754297023, 7802.726904558342, 1761.4363403710818, 2094.7695753467106, 2113.2286715348864, 2127.1385089038963, 2129.861558998668, 1823.7446757453968, 1850.3708739028727, 1850.9167087481096, 1851.1022154255174, 2041.795820417961, 2056.321413933398, 2102.232685069946, 2107.1676687708346, 2107.2940564910773, 2109.1457959526415, 2067.4934641013047, 2085.092984551717, 2089.8014688604558, 2089.9268525601533, 2095.0467537270506, 2066.700328452366, 2117.98941005294, 2120.491082140275, 2120.9893950530304, 2124.3945475539554, 7554.131274903401, 7588.354989825872, 7669.769906902802, 7822.413319001098, 7833.451658383916, 4508.797104130322, 4642.721436713808, 4657.86026419208, 4660.16117098922, 4663.114008338405, 2930.369970456362, 2955.083923983572, 2967.5251959686348, 3017.3814368054714, 3022.788404811664, 1100.5817789240118, 1102.322071925768, 1124.7075760302298, 1127.577158565538, 1154.6536039188295, 10603.151747860153, 10629.043386095289, 10629.681109566725, 10630.946845265802, 10669.19981001428, 4007.859724909614, 4229.873103806891, 4262.403263543107, 4289.764062976504, 4306.956930430656, 9099.727008189764, 9103.954480227689, 9108.271338292921, 9114.36199466031, 9124.49815260167, 4687.437507499054, 4711.081339138835, 4717.198076327033, 4719.2921061841, 4723.0789995951145, 4045.0089728016824, 4164.770937598461, 4265.1469706058915, 4266.5946735060115, 4314.848980285675, 3270.296886169485, 3357.0, 3549.1836877518317, 3592.946105808415, 3667.5965643779164, 1189.9107566932514, 1201.375284851872, 1223.5350566784564, 1225.503671013241, 1226.4113225651738, 679.3003206697011, 686.0722364463408, 691.246541270018, 698.2947223304499, 719.086759815032, 4631.421072365954, 4643.883902902455, 4664.346991421204, 4669.322948172496, 4733.479317275097, 782.2842099479027, 859.5186695450568, 860.5009094725044, 866.2766589897507, 873.3188113271658, 3129.4210571044428, 3184.7611429142908, 3215.083701145162, 3235.6440791512914, 3342.6824451677053, 9043.231325337289, 9389.201917836925, 9533.285003624756, 9533.523323833919, 9556.426614403159, 8700.912990870136, 9476.478793666276, 9487.383320084173, 9498.287628427895, 9506.85739713891, 6983.441324694114, 7295.598742069237, 7311.451641126937, 7324.707011719472, 7358.7424440145405, 4850.733209673502, 4921.021860197724, 5046.34397528319, 5063.392392912874, 5069.0, 8187.959060204661, 8338.79153021155, 8494.150584941704, 8596.0, 8603.354748393771, 8534.274586660204, 8724.956375218084, 8761.91238087611, 8763.211310982042, 8824.5587720615, 6693.866122677617, 6769.796906092825, 6818.386345228953, 6833.487488438486, 6834.863302733818, 6395.520335974914, 6642.269350371359, 7324.597147156957, 7368.557886526825, 7414.962925185339, 6377.8724425512155, 6496.350364963655, 6553.737850486118, 6657.7669781557415, 6686.197656281277, 6607.108040414482, 6649.966750166218, 6668.0, 6688.197416310074, 6710.194776626836, 5406.270153529381, 5433.782648694166, 5442.836714898559, 5443.183522471661, 5446.237526746338, 4279.529251782364, 4321.848735294376, 4348.847790327323, 4388.587494555122, 4483.439570053743, 4346.587074227943, 4351.673624478113, 4369.978150109229, 4453.332000199866, 4586.082783443391], "value": 4003.352111895046, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.73971261266655, 362.57216484548235, 369.3407268026573, 370.81088644791146, 373.3708700839088, 4732.5977291930185, 4797.232442809147, 4808.230683090711, 4818.108649899771, 4854.6844455110395, 4599.793009314581, 4715.481297057321, 4756.619470442365, 4785.712857228568, 4788.281757736333, 3774.698024158061, 3783.1298801275725, 3783.2433513297315, 3786.4630697432785, 3788.337041017811, 3783.5270591176104, 3785.3943369060817, 3790.4693342931873, 3791.748722921443, 3795.601461846508, 3602.2255215128744, 3736.4680481002792, 3756.1736417988136, 3758.5677647070524, 3765.4728338032696, 3698.889033328991, 3704.8329776120704, 3711.870084547055, 3714.6040803681476, 3745.250949810049, 3286.14560214346, 3291.358185153896, 3292.3579901919134, 3294.7199488043543, 3296.670332966708, 4649.349091127245, 4661.417322834645, 4663.51033141522, 4673.415823022122, 4677.508861569554, 4051.8784436466954, 4228.302330115527, 4251.404803327506, 4258.765767882765, 4275.807588658487, 4972.577330926875, 4977.0, 4981.551660350586, 4990.176620857555, 4996.300517927493, 3565.5186549815703, 3576.2489877125836, 3582.176099497104, 3611.94582081269, 3613.0, 4792.904141917142, 4934.309196712463, 5117.5905927526455, 5206.193040078794, 5226.634135610509, 3654.4734331370355, 3662.4506324051604, 3664.9816750916607, 3667.688246499024, 3676.117731744362, 3643.1256498440184, 3679.816009199529, 3684.3184010958057, 3705.7591256568235, 3709.276691045274, 3933.822977965941, 4008.158286759755, 4029.597040295947, 4206.558311377261, 4207.137536804948, 4628.328892310664, 4906.950930490721, 4977.253411988231, 5015.673981191211, 5017.9247311290355, 1330.5210124355224, 1334.2928248028365, 1338.4941940317144, 1351.1285221032379, 1351.4796803230827, 875.0, 933.5565606337071, 934.7709811096325, 936.5036530638772, 942.8444306689456, 4234.13697972891, 4254.553271906405, 4263.808128634157, 4265.4881414230495, 4271.765052922119, 4982.626303027288, 4987.650864439454, 4991.876827713735, 4993.325901003391, 4993.725345106054, 4050.6556942659613, 4235.9621892636505, 4247.235497610398, 4266.914661706748, 4276.685568684233, 2610.5301045811934, 2635.9736402636113, 2639.2214296782495, 2652.3634327761197, 2659.880305386262, 4716.716996980123, 4728.59806916416, 4733.171694953369, 4742.976285118552, 4747.667663263607, 4794.2808578713475, 5087.008033433519, 5096.5791249156355, 5109.56568691658, 5122.846314610568, 4703.929441058387, 4722.598579120676, 4730.266808644666, 4730.763461826895, 4763.3807605011125, 4960.6031517477795, 4964.733992831949, 4983.508245877061, 4991.176455884738, 4991.57571606403, 4744.478107408113, 4919.803207871645, 4935.71774769774, 4938.061768264019, 4957.529034741694, 4646.930296045494, 4798.376211092535, 4806.890108956792, 4830.903381932412, 4887.84733846674, 3581.964180358163, 3679.1537946273043, 3689.2252626948643, 3690.206605579706, 3695.6489133533346, 5137.229415587692, 5139.911487627148, 5146.356705411824, 5151.948480515147, 5154.252633368065, 4863.659543831898, 4961.206207007006, 4974.17926042199, 5007.223880298559, 5019.171836646912, 4988.1270777613045, 5030.446650868328, 5034.496550344937, 5043.899122017612, 5046.495350465071, 4965.106280869478, 4970.25446183061, 4974.37820272466, 4990.426100998432, 4991.9500804991485, 4782.832600858953, 4970.776315065758, 5003.249512573143, 5025.045241404122, 5082.136036873666, 4869.513048695102, 4890.315355850255, 4907.803687852446, 4910.140725372974, 4927.778249978831], "value": 3773.250318539863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.4486712967333, 658.7101675262884, 714.164427619685, 724.4530379563429, 745.269635756958, 12097.516099356026, 12556.686082847933, 12574.056945729073, 12585.056120790954, 12587.0, 12587.874121258785, 12745.0, 12758.106932514727, 12773.0, 12788.68028299293, 783.6316931042401, 786.1116937860224, 804.8350088231898, 810.3517186251005, 812.8374325134968, 813.8290958898639, 824.8061705499233, 824.8968878890139, 825.8637324841424, 826.3678286111129, 854.2482615298528, 854.6666799947994, 856.3363393370157, 856.4133568505594, 856.5802756649229, 849.8980122385353, 854.1757204297883, 854.974350769481, 855.0893298636994, 855.5465603230298, 627.8618703885138, 629.5624540944, 629.8551333193345, 631.1384959530254, 631.356016862802, 12364.258144511357, 12498.937505312506, 12622.873771262262, 12659.050571207197, 13828.0, 11717.0, 11920.344381059038, 11998.064345544222, 12119.939400302943, 12193.817092743617, 9768.218542516654, 9834.557444914995, 9914.454704991223, 9991.550380232835, 10036.447995360253, 3197.936041279185, 3202.5676533668125, 3208.6149662040702, 3224.790388624732, 3237.7733558650903, 57168.42831571672, 57633.13550296749, 57838.42161578372, 58428.68497218166, 59056.40943590552, 10146.188304935735, 10186.185105191567, 10367.0, 10383.740406489898, 10401.84397234042, 18720.15759290808, 18725.531861703566, 18905.90547047256, 18917.621647567248, 18973.146208420654, 6262.373762623793, 6265.404786545362, 6318.557700960889, 6398.104265402847, 6444.033394990789, 3592.946105808415, 3604.693601043888, 3608.332458495187, 3645.6901163400876, 3652.1782598915033, 2897.652281726206, 2935.0901220621677, 2974.301039255773, 2987.507061334899, 2993.970060299369, 492.1289317907284, 493.92591111333587, 501.5260578753116, 501.70900877490965, 503.98236061738396, 593.7061154728351, 608.6409018679001, 616.608453631947, 619.123939625425, 622.5237693164732, 8146.389020823462, 8292.709755158661, 8385.136205258113, 8456.577171141418, 8847.336449766293, 539.3554702130995, 539.4147350125158, 557.1306288253232, 558.8731049776494, 561.6405500479697, 3944.783036932939, 4015.2170326785995, 4080.755154690669, 4115.567865374092, 4208.431861698631, 58213.0, 60247.0, 61152.694236528536, 62848.68575657092, 63259.6837015812, 42933.35599966003, 44283.33574996378, 45040.0, 45790.542094579294, 48761.0, 45157.54842451599, 45945.0, 46806.53193468022, 46820.76589617098, 47905.76047119742, 8069.59652017385, 8608.91391086081, 8670.956645216733, 8707.0, 8730.869036964577, 31492.370152596362, 32696.34607307793, 32731.181720456225, 33717.83141084279, 33963.4905476423, 38399.42400864045, 39495.210095797345, 39881.0, 40260.3960940592, 40740.0, 9937.155341796028, 10059.0, 10133.847992279974, 10167.84748228792, 10177.236707247133, 12056.326874056267, 12107.33409662477, 12258.20321679088, 12414.0, 12867.163634363736, 10774.191935604704, 10966.890331096587, 10967.23229373968, 11205.943970280423, 11477.942610286895, 10522.263441559018, 10649.680509584725, 10929.672109836718, 11047.72380690489, 11062.225644205151, 10592.788144237225, 10653.978638514982, 10899.455027248447, 11222.102231821598, 11283.266587671775, 9000.909990900007, 9022.458652480873, 9038.728838134604, 9204.355695101278, 9465.66870159541, 8328.250457458858, 8680.0, 8810.251128654136, 9020.323475739215, 9117.589708463267], "value": 6049.4242358019565, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 116.0, 115.0, 114.0, 114.0, 115.0, 113.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 131.0, 124.0, 166.0, 128.0, 127.0, 142.0, 134.0, 150.0, 126.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 160.0, 160.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 114.0, 77.0, 78.0, 76.0, 77.0, 103.0, 76.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 106.0, 84.0, 84.0, 84.0, 109.0, 85.0, 85.0, 108.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 149.0, 150.0, 149.0, 148.0, 150.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 63.0, 62.0, 64.0, 63.0, 62.0, 63.0, 65.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 71.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 86.0, 87.0, 89.0, 88.0, 87.0, 87.0, 86.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 145.0, 136.0, 137.0, 139.0, 134.0, 136.0, 135.0, 179.0, 152.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 195.0, 187.0, 190.0, 184.0, 185.0, 186.0, 185.0, 195.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 71.0, 71.0, 76.0, 71.0, 71.0, 71.0, 70.0, 70.0], "value": 71.6, "unit": "score"}]}]} 17:14:00 INFO - Return code: 0 17:14:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:14:00 ERROR - # TBPL SUCCESS # 17:14:00 INFO - Running post-action listener: _resource_record_post_action 17:14:00 INFO - [mozharness: 2017-02-16 01:14:00.582821Z] Finished run-tests step (success) 17:14:00 INFO - Running post-run listener: _resource_record_post_run 17:14:00 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 0; Write bytes: 411754496; Read time: 0; Write time: 244600 17:14:00 INFO - TinderboxPrint: CPU usage
13.1% 17:14:00 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:14:00 INFO - TinderboxPrint: I/O write bytes / time
411,754,496 / 244,600 17:14:00 INFO - TinderboxPrint: CPU idle
8,348.7 (86.9%) 17:14:00 INFO - TinderboxPrint: CPU user
1,215.1 (12.6%) 17:14:00 INFO - TinderboxPrint: Swap in / out
0 / 0 17:14:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111788032; Read time: 0; Write time: 60180 17:14:00 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 298835968; Read time: 0; Write time: 184372 17:14:01 INFO - Running post-run listener: _upload_blobber_files 17:14:01 INFO - Blob upload gear active. 17:14:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:14:01 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 17:14:01 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:14:01 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:14:01 INFO - (blobuploader) - INFO - Open directory for files ... 17:14:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:14:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:14:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:02 INFO - (blobuploader) - INFO - Done attempting. 17:14:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:14:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:14:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:05 INFO - (blobuploader) - INFO - Done attempting. 17:14:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:14:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:14:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:06 INFO - (blobuploader) - INFO - Done attempting. 17:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:14:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:07 INFO - (blobuploader) - INFO - Done attempting. 17:14:07 INFO - (blobuploader) - INFO - Iteration through files over. 17:14:07 INFO - Return code: 0 17:14:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:14:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:14:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f229c3738e6eeed292760f7468cacd5995bb40973d531bc9d9c117d2cab2c94385ba20cba7251d730185cb5b1a749e68e4b12c7ec37eeac2d8dad2de97e3217", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e69be477eff425e38c2155142a80d0456affa5d9c03bc753070c34a7e850e81707888c49961a7469454e1f2b3639a0057d37b5d9ea914422c32ab4a928f309df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a3e9868c0fac0f6b89fd56b9d96fcda90cef66ad73c745630ada4f35101b44a1841224c884f124eec58cecbd89b5074d5df8f88c93dac8e7227ded5487687de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1740dfaf58b977c8e34a67b97c6ed96f7def72202a2dad3b7f6fe29846d69a116d146a68ce617e1a944731f5b5b7bea0abca3ad8c85ace8f240aee369fd74d5"} 17:14:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:14:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:14:07 INFO - Contents: 17:14:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f229c3738e6eeed292760f7468cacd5995bb40973d531bc9d9c117d2cab2c94385ba20cba7251d730185cb5b1a749e68e4b12c7ec37eeac2d8dad2de97e3217", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e69be477eff425e38c2155142a80d0456affa5d9c03bc753070c34a7e850e81707888c49961a7469454e1f2b3639a0057d37b5d9ea914422c32ab4a928f309df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a3e9868c0fac0f6b89fd56b9d96fcda90cef66ad73c745630ada4f35101b44a1841224c884f124eec58cecbd89b5074d5df8f88c93dac8e7227ded5487687de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1740dfaf58b977c8e34a67b97c6ed96f7def72202a2dad3b7f6fe29846d69a116d146a68ce617e1a944731f5b5b7bea0abca3ad8c85ace8f240aee369fd74d5"} 17:14:07 INFO - Running post-run listener: copy_logs_to_upload_dir 17:14:07 INFO - Copying logs to upload dir... 17:14:07 INFO - mkdir: /builds/slave/test/build/upload/logs 17:14:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.575534 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-15 17:14:07.627080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 17:14:07.634871) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f229c3738e6eeed292760f7468cacd5995bb40973d531bc9d9c117d2cab2c94385ba20cba7251d730185cb5b1a749e68e4b12c7ec37eeac2d8dad2de97e3217", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e69be477eff425e38c2155142a80d0456affa5d9c03bc753070c34a7e850e81707888c49961a7469454e1f2b3639a0057d37b5d9ea914422c32ab4a928f309df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a3e9868c0fac0f6b89fd56b9d96fcda90cef66ad73c745630ada4f35101b44a1841224c884f124eec58cecbd89b5074d5df8f88c93dac8e7227ded5487687de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1740dfaf58b977c8e34a67b97c6ed96f7def72202a2dad3b7f6fe29846d69a116d146a68ce617e1a944731f5b5b7bea0abca3ad8c85ace8f240aee369fd74d5"} build_url:https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/QU5xlODFTFukOLooyHFytA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f229c3738e6eeed292760f7468cacd5995bb40973d531bc9d9c117d2cab2c94385ba20cba7251d730185cb5b1a749e68e4b12c7ec37eeac2d8dad2de97e3217", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e69be477eff425e38c2155142a80d0456affa5d9c03bc753070c34a7e850e81707888c49961a7469454e1f2b3639a0057d37b5d9ea914422c32ab4a928f309df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a3e9868c0fac0f6b89fd56b9d96fcda90cef66ad73c745630ada4f35101b44a1841224c884f124eec58cecbd89b5074d5df8f88c93dac8e7227ded5487687de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1740dfaf58b977c8e34a67b97c6ed96f7def72202a2dad3b7f6fe29846d69a116d146a68ce617e1a944731f5b5b7bea0abca3ad8c85ace8f240aee369fd74d5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 17:14:07.662406) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 17:14:07.662823) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1487206382.467967-53620044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 17:14:07.742081) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 17:14:07.742610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 17:14:07.742905) ========= ========= Total master_lag: 0.18 =========